Hello all,

I'm having a problem with a scrip running in the TransactionBatch stage.

Setting the scene:

RT 3.6.3, Oracle, Apache2, Perl 5.8.8

One queue required a custom field to be populated with information
extracted from an e-mail message on a ticket creation.  The initial
notification to the AdminCcs needed to have the information from the CF
in the message.  To make this work, I set $UseTransactionBatch, and
modified the scrip 'On Create, Notify AdminCcs' to execute in the
Transaction Batch stage.

On e-mail from unprivileged users to the queue, everything works.  User
gets their notification back, CF is populated, and AdminCcs get their
notification with the CF.

Switch to another Queue: Same configuration, except it doesn't have the
CF or the scrip to extract and populate that CF.

If an unprivileged sends e-mail to this queue, they get their
notification, but the AdminCcs do not.

If a privileged user sends e-mail to this queue, they get their
notification AND the AdminCcs get their notification.

It was suggested on IRC by Ruslan that I try RT 3.6.4rc1, since it
addressed some TransactionBatch issues.  This, however, did not help.
He then asked me to send mail here. :-)

Attached are some logs, broken apart by case.  Let me know if there's
any more information I can provide!

Thanks in advance,

David

--
David Stipp <[EMAIL PROTECTED]>
********************************************************************************
********************************************************************************
Problem Queue:  SBIT

********************************************************************************
Unprivileged user sending e-mail

[Mon May 21 18:53:39 2007] [debug]: RT::Date used date::parse to make 
1970-01-01 21600 (/services/rt/rt3/lib/RT/Date.pm:211)
[Mon May 21 18:55:40 2007] [debug]: Converting 'us-ascii' to 'utf-8' for 
text/plain - test from admin@< XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/lib/RT/I18N.pm:223)
[Mon May 21 18:55:40 2007] [debug]: About to think about scrips for transaction 
#18785 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:55:40 2007] [debug]: About to think about scrips for transaction 
#18786 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:55:40 2007] [debug]: About to think about scrips for transaction 
#18787 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:55:40 2007] [debug]: About to think about scrips for transaction 
#18788 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:55:40 2007] [debug]: About to think about scrips for transaction 
#18789 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:55:40 2007] [debug]: About to prepare scrips for transaction 
#18789 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:169)
[Mon May 21 18:55:40 2007] [debug]: Found 3 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)
[Mon May 21 18:55:40 2007] [debug]: About to commit scrips for transaction 
#18789 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:178)
[Mon May 21 18:55:40 2007] [info]: <rt-3.6.3-14794-1179773740-285.533-30-0@< XX 
HOSTNAME REMOVED XX >> #533/18789 - Scrip 30 100-OnCreateAutoreplyToRequestors 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:243)
[Mon May 21 18:55:41 2007] [info]: <rt-3.6.3-14794-1179773740-285.533-30-0@< XX 
HOSTNAME REMOVED XX >> sent  To: admin@< XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:274)
[Mon May 21 18:55:41 2007] [debug]: About to think about scrips for transaction 
#18790 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:55:41 2007] [info]: Ticket 533 created in queue 'SBIT' by 
admin@< XX HOSTNAME REMOVED XX > (/services/rt/rt3/lib/RT/Ticket_Overlay.pm:754)
[Mon May 21 18:55:41 2007] [debug]: Found 0 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)

********************************************************************************
Privileged user sending e-mail

[Mon May 21 18:47:06 2007] [debug]: Converting 'ISO-8859-1' to 'utf-8' for 
text/plain - testing the queue thingie (/services/rt/rt3/lib/RT/I18N.pm:223)
[Mon May 21 18:47:06 2007] [debug]: About to think about scrips for transaction 
#18766 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:47:06 2007] [debug]: About to think about scrips for transaction 
#18767 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:47:06 2007] [debug]: About to think about scrips for transaction 
#18768 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:47:06 2007] [debug]: About to think about scrips for transaction 
#18769 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:47:07 2007] [debug]: About to think about scrips for transaction 
#18770 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:47:07 2007] [debug]: About to prepare scrips for transaction 
#18770 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:169)
[Mon May 21 18:47:07 2007] [debug]: Found 3 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)
[Mon May 21 18:47:07 2007] [debug]: About to commit scrips for transaction 
#18770 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:178)
[Mon May 21 18:47:07 2007] [info]: <rt-3.6.3-14797-1179773227-856.530-30-0@< XX 
HOSTNAME REMOVED XX >> #530/18770 - Scrip 30 100-OnCreateAutoreplyToRequestors 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:243)
[Mon May 21 18:47:07 2007] [info]: <rt-3.6.3-14797-1179773227-856.530-30-0@< XX 
HOSTNAME REMOVED XX >> sent  To: < XX E-MAIL ADDRESS REMOVED XX > 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:274)
[Mon May 21 18:47:07 2007] [debug]: About to think about scrips for transaction 
#18771 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Mon May 21 18:47:07 2007] [info]: Ticket 530 created in queue 'SBIT' by 
boydbria (/services/rt/rt3/lib/RT/Ticket_Overlay.pm:754)
[Mon May 21 18:47:07 2007] [debug]: Found 1 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)
[Mon May 21 18:47:08 2007] [info]: <rt-3.6.3-14797-1179773228-522.530-4-0@< XX 
HOSTNAME REMOVED XX >> #530/18770 - Scrip 4 075-OnCreateNotifyAdminCcs 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:243)
[Mon May 21 18:47:08 2007] [info]: <rt-3.6.3-14797-1179773228-522.530-4-0@< XX 
HOSTNAME REMOVED XX >> sent  Bcc: < XX E-MAIL ADDRESS REMOVED XX > 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:274)
[Mon May 21 18:47:08 2007] [debug]: About to think about scrips for transaction 
#18772 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)


********************************************************************************
********************************************************************************
Working Queue:  SMGp

********************************************************************************
Unprivileged user sending e-mail

[Fri May 18 15:50:43 2007] [debug]: Guessed encoding: ascii 
(/services/rt/rt3/lib/RT/I18N.pm:399)
[Fri May 18 15:50:43 2007] [debug]: Guessed encoding: ascii 
(/services/rt/rt3/lib/RT/I18N.pm:399)
[Fri May 18 15:50:43 2007] [debug]: Converting 'ascii' to 'utf-8' for 
text/plain - Message from Swatch on < XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/lib/RT/I18N.pm:223)
[Fri May 18 15:50:43 2007] [debug]: CreateFromExternalUserInfo: entered with 
args:  mail root@< XX HOSTNAME REMOVED XX >  
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:55)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo: Entered with:
        key   = mail
        value = root@< XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:198)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo:  Attempting TLS 
LDAP lookup (/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:210)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo: First search filter 
 'mail=root@< XX HOSTNAME REMOVED XX >' 
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:269)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo:  Search produced  0 
 results (/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:281)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo: Leaving LDAP 
examination  with:
        Name         = 
        EmailAddress = 
        RealName     = 
        Found        = 0 
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:302)
[Fri May 18 15:50:43 2007] [debug]: CreateFromExternalUserInfo: params hash:  
RealName  Name  EmailAddress   
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:58)
[Fri May 18 15:50:43 2007] [info]: CreateFromExternalUserInfo: failed to find 
user with args:  mail root@< XX HOSTNAME REMOVED XX >  
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:60)
[Fri May 18 15:50:43 2007] [debug]: CreateFromExternalUserInfo: entered with 
args:  cn root@< XX HOSTNAME REMOVED XX >  
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:55)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo: Entered with:
        key   = cn
        value = root@< XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:198)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo:  Attempting TLS 
LDAP lookup (/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:210)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo: First search filter 
 'cn=root@< XX HOSTNAME REMOVED XX >' 
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:269)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo:  Search produced  0 
 results (/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:281)
[Fri May 18 15:50:43 2007] [debug]: LookupExternalUserInfo: Leaving LDAP 
examination  with:
        Name         = 
        EmailAddress = 
        RealName     = 
        Found        = 0 
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:302)
[Fri May 18 15:50:43 2007] [debug]: CreateFromExternalUserInfo: params hash:  
RealName  Name  EmailAddress   
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:58)
[Fri May 18 15:50:43 2007] [info]: CreateFromExternalUserInfo: failed to find 
user with args:  cn root@< XX HOSTNAME REMOVED XX >  
(/services/rt/rt3/local/lib/RT/CurrentUser_Local.pm:60)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18562 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18563 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18564 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18565 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18566 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18567 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18568 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to prepare scrips for transaction 
#18568 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:169)
[Fri May 18 15:50:44 2007] [debug]: Found 4 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)
[Fri May 18 15:50:44 2007] [debug]: About to commit scrips for transaction 
#18568 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:178)
[Fri May 18 15:50:44 2007] [debug]: load cf Hostname 
(/services/rt/rt3/local/lib/RT/Action/ExtractCustomFieldValues.pm:38)
[Fri May 18 15:50:44 2007] [debug]: load cf done: 1 Found Object 
(/services/rt/rt3/local/lib/RT/Action/ExtractCustomFieldValues.pm:44)
[Fri May 18 15:50:44 2007] [debug]: look for cf in Header RT-Hostname 
(/services/rt/rt3/local/lib/RT/Action/ExtractCustomFieldValues.pm:56)
[Fri May 18 15:50:44 2007] [debug]: matched value: < XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/local/lib/RT/Action/ExtractCustomFieldValues.pm:61)
[Fri May 18 15:50:44 2007] [debug]: found value for cf: < XX HOSTNAME REMOVED 
XX > (/services/rt/rt3/local/lib/RT/Action/ExtractCustomFieldValues.pm:79)
[Fri May 18 15:50:44 2007] [debug]: About to think about scrips for transaction 
#18569 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:44 2007] [debug]: About to prepare scrips for transaction 
#18569 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:169)
[Fri May 18 15:50:44 2007] [debug]: Found 2 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)
[Fri May 18 15:50:44 2007] [debug]: About to commit scrips for transaction 
#18569 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:178)
[Fri May 18 15:50:45 2007] [info]: CustomFieldValue (Hostname,< XX HOSTNAME 
REMOVED XX >) added: 1 Hostname < XX HOSTNAME REMOVED XX > added 
(/services/rt/rt3/local/lib/RT/Action/ExtractCustomFieldValues.pm:82)
[Fri May 18 15:50:45 2007] [info]: <rt-3.6.3-13449-1179503444-1808.521-25-0@< 
XX HOSTNAME REMOVED XX >> #521/18568 - Scrip 25 
100-OnCreateAutoreplyToRequestors 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:243)
[Fri May 18 15:50:45 2007] [info]: <rt-3.6.3-13449-1179503444-1808.521-25-0@< 
XX HOSTNAME REMOVED XX >> sent  To: root@< XX HOSTNAME REMOVED XX > 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:274)
[Fri May 18 15:50:45 2007] [debug]: About to think about scrips for transaction 
#18570 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)
[Fri May 18 15:50:45 2007] [info]: Ticket 521 created in queue 'SMG-p' by 
root@< XX HOSTNAME REMOVED XX > (/services/rt/rt3/lib/RT/Ticket_Overlay.pm:754)
[Fri May 18 15:50:45 2007] [debug]: Found 1 scrips 
(/services/rt/rt3/lib/RT/Scrips_Overlay.pm:363)
[Fri May 18 15:50:45 2007] [info]: <rt-3.6.3-13449-1179503445-1029.521-4-0@< XX 
HOSTNAME REMOVED XX >> #521/18568 - Scrip 4 075-OnCreateNotifyAdminCcs 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:243)
[Fri May 18 15:50:46 2007] [info]: <rt-3.6.3-13449-1179503445-1029.521-4-0@< XX 
HOSTNAME REMOVED XX >> sent  Bcc: < XX E-MAIL ADDRESS REMOVED XX >, < XX E-MAIL 
ADDRESS REMOVED XX >, < XX E-MAIL ADDRESS REMOVED XX >, < XX E-MAIL ADDRESS 
REMOVED XX >, < XX E-MAIL ADDRESS REMOVED XX >, < XX E-MAIL ADDRESS REMOVED XX 
>, < XX E-MAIL ADDRESS REMOVED XX >, < XX E-MAIL ADDRESS REMOVED XX > 
(/services/rt/rt3/lib/RT/Action/SendEmail.pm:274)
[Fri May 18 15:50:46 2007] [debug]: About to think about scrips for transaction 
#18571 (/services/rt/rt3/lib/RT/Transaction_Overlay.pm:165)

_______________________________________________
http://lists.bestpractical.com/cgi-bin/mailman/listinfo/rt-users

Community help: http://wiki.bestpractical.com
Commercial support: [EMAIL PROTECTED]


Discover RT's hidden secrets with RT Essentials from O'Reilly Media. 
Buy a copy at http://rtbook.bestpractical.com

Reply via email to