Ok I think I have found part of the problem. We use this originate statement to auto dial numbers from another application.
originate Local/301@extensions-auto extension XXXXXXXXXX@outbound
However when I use the originate to dial the number the CDR record is updated but the userfield id is never actually loaded.
I really have no idea why this would be.
Here is the output from the CLI not using originate where the update works:
-- Executing [1XXXXXXXXXX@outbound:1] NoOp("IAX2/301-5766", "Outbound Context") in new stack
-- Executing [1XXXXXXXXXX@outbound:2] Macro("IAX2/301-5766", "dial|Zap/g2/1XXXXXXXXXX") in new stack
-- Executing [s@macro-dial:1] NoOp("IAX2/301-5766", "Dial Macro ARG1 = Dial String ARG2 = Timeout") in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:2] NoOp(“IAX2/301-5766”, "ARG1: Zap/g2/1XXXXXXXXXX ARG2 ") in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:3] NoOp(“IAX2/301-5766”, “Callerid: 301”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:4] BackTicks(“IAX2/301-5766”, “CDR_UNIQUE|/usr/local/scripts/uuidgen.pl”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Backticks
– Executing [s@macro-dial:5] NoOp(“IAX2/301-5766”, “Unique ID: 1b2899af-7484-4ab9-bc94-ea511217f060”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:6] Set(“IAX2/301-5766”, “CDR(userfield)=1b2899af-7484-4ab9-bc94-ea511217f060”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Set
– Executing [s@macro-dial:7] Set(“IAX2/301-5766”, “CALLFILENAME=n1233800163.58-301-02042009-211603~1b2899af-7484-4ab9-bc94-ea511217f060~.vox”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: Set
– Executing [s@macro-dial:8] MixMonitor(“IAX2/301-5766”, “n1233800163.58-301-02042009-211603~1b2899af-7484-4ab9-bc94-ea511217f060~.vox|W(1)|/usr/local/scripts/update_recording_file.pl n1233800163.58-301-02042009-211603~1b2899af-7484-4ab9-bc94-ea511217f060~.vox”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: Executed application: MixMonitor
– Executing [s@macro-dial:9] GotoIf(“IAX2/301-5766”, “1?dial-default:dial-to”) in new stack
– Goto (macro-dial,s,10)
[Feb 4 18:16:03] DEBUG[14661]: app_macro.c:337 _macro_exec: == Begin MixMonitor Recording IAX2/301-5766
Executed application: GotoIf
– Executing [s@macro-dial:10] Dial(“IAX2/301-5766”, “Zap/g2/1XXXXXXXXXX||TrH”) in new stack
[Feb 4 18:16:03] DEBUG[14661]: chan_zap.c:1948 zt_call: Dialing ‘1XXXXXXXXXX’
[Feb 4 18:16:03] DEBUG[14661]: chan_zap.c:2024 zt_call: Deferring dialing…
– Called g2/1XXXXXXXXXX
[Feb 4 18:16:03] DEBUG[14661]: chan_zap.c:4377 zt_handle_event: Ignoring wink on channel 37
[Feb 4 18:16:03] DEBUG[14661]: chan_zap.c:4440 zt_handle_event: Sent deferred digit string: T1XXXXXXXXXXw
– Zap/37-1 answered IAX2/301-5766
– Hungup ‘Zap/37-1’
== Spawn extension (macro-dial, s, 10) exited non-zero on ‘IAX2/301-5766’ in macro ‘dial’
== Spawn extension (macro-dial, s, 10) exited non-zero on ‘IAX2/301-5766’
== End MixMonitor Recording IAX2/301-5766
== Executing [/usr/local/scripts/update_recording_file.pl n1233800163.58-301-02042009-211603~1b2899af-7484-4ab9-bc94-ea511217f060~.vox]
– Hungup ‘IAX2/301-5766’
Here is the output from the CLI when the database is not updated with the userfield id:
originate Local/301@extensions-auto extension XXXXXXXXXX@outbound
– Executing [301@extensions-auto:1] NoOp(“Local/301@extensions-auto-423a,2”, “extentions |IAX2/301”) in new stack
– Executing [301@extensions-auto:2] Macro(“Local/301@extensions-auto-423a,2”, “stduser|IAX2/301|301”) in new stack
– Executing [s@macro-stduser:1] NoOp(“Local/301@extensions-auto-423a,2”, “Std User Macro ARG1 = Dial String ARG2 = VM Box ARG3 = Timeout”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-stduser:2] NoOp(“Local/301@extensions-auto-423a,2”, "ARG1: IAX2/301 ARG2 301 ARG3 ") in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:;40m337 _macro_exec: Executed application: Noop
– Executing [s@macro-stduser:3] NoOp(“Local/301@extensions-auto-423a,2”, "Callerid: ") in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-stduser:4] GotoIf(“Local/301@extensions-auto-423a,2”, “1?dial-default:dial-to”) in new stack
– Goto (macro-stduser,s,5)
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: GotoIf
– Executing [s@macro-stduser:5] Macro(“Local/301@extensions-auto-423a,2”, “dial|IAX2/301|15”) in new stack
– Executing [s@macro-dial:1] NoOp7;40m(“Local/301@extensions-auto-423a,2”, “Dial Macro ARG1 = Dial String ARG2 = Timeout”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:2] NoOp(“Local/301@extensions-auto-423a,2”, “ARG1: IAX2/301 ARG2 15”) in new stack
[Feb 4 18:11:31] 40mDEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:3] NoOp(“Local/301@extensions-auto-423a,2”, "Callerid: ") in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:4] 1;36;40mBackTicks(“Local/301@extensions-auto-423a,2”, “CDR_UNIQUE|/usr/local/scripts/uuidgen.pl”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Backticks
– Executing [s@macro-dial:5] NoOp(“Local/301@extensions-auto-423a,2”, “Unique ID: 4092be89-32ab-4a34-b6c4-b462aebeb663[color=red][0;37;40m[/color]”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:6] Set(“Local/301@extensions-auto-423a,2”, “CDR(userfield)=4092be89-32ab-4a34-b6c4-b462aebeb663”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 40m_macro_exec: Executed application: Set
– Executing [s@macro-dial:7] Set(“Local/301@extensions-auto-423a,2”, “CALLFILENAME=n1233799891.55–02042009-211131~4092be89-32ab-4a34-b6c4-b462aebeb663~.vox”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: Set
– Executing [s@macro-dial:8] MixMonitor(“Local/301@extensions-auto-423a,2”, “n1233799891.55–02042009-211131~4092be89-32ab-4a34-b6c4-b462aebeb663~.vox|W(1)|/usr/local/scripts/update_recording_file.pl n1233799891.55–02042009-211131~4092be89-32ab-4a34-b6c4-b462aebeb663~.vox”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: MixMonitor
– Executing [s@macro-dial:9] [1;36;40mGotoIf(“Local/301@extensions-auto-423a,2”, “0?dial-default:dial-to”) in new stack
– Goto (macro-dial,s,12)
[Feb 4 18:11:31] DEBUG[14641]: app_macro.c:337 _macro_exec: Executed application: GotoIf
– Executing [s@macro-dial:12] Dial(“Local/301@extensions-auto-423a,2”, “IAX2/301|15|TrH”) in new stack
[Feb 4 18:11:31] DEBUG[14641]: chan_iax2.c:3062 create_addr: prepending 4 to prefs
– Called 301
== Begin MixMonitor Recording Local/301@extensions-auto-423a,2
– Call accepted by 192.168.39.205 (format ulaw)
– Format for call is ulaw
– IAX2/301-5970 is ringing
– IAX2/301-5970 answered Local/301@extensions-auto-423a,2
-- Executing [XXXXXXXXXX@outbound:1] NoOp("Local/301@extensions-auto-423a,1", "Outbound Context No 1") in new stack
-- Executing [XXXXXXXXXX@outbound:2] Goto("Local/301@extensions-auto-423a,1", "1XXXXXXXXXX|1") in new stack
-- Goto (outbound,1XXXXXXXXXX,1)
-- Executing [1XXXXXXXXXX@outbound:1] NoOp("Local/301@extensions-auto-423a,1", "Outbound Context") in new stack
-- Executing [1XXXXXXXXXX@outbound:2] Macro("Local/301@extensions-auto-423a,1", "dial|Zap/g2/1XXXXXXXXXX") in new stack
-- Executing [s@macro-dial:1] NoOp("Local/301@extensions-auto-423a,1", "Dial Macro ARG1 = Dial String ARG2 = Timeout") in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:2] NoOp(“Local/301@extensions-auto-423a,1”, "ARG1: Zap/g2/1XXXXXXXXXX ARG2 ") in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:3] NoOp(“Local/301@extensions-auto-423a,1”, "Callerid: ") in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:4] BackTicks(“Local/301@extensions-auto-423a,1”, “CDR_UNIQUE|/usr/local/scripts/uuidgen.pl”) in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Backticks
– Executing [s@macro-dial:5] NoOp(“Local/301@extensions-auto-423a,1”, “Unique ID: 0a3f0361-5350-4c15-8500-75e62ef79527”) in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Noop
– Executing [s@macro-dial:6] Set(“Local/301@extensions-auto-423a,1”, “CDR(userfield)=0a3f0361-5350-4c15-8500-75e62ef79527”) in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Set
– Executing [s@macro-dial:7] Set(“Local/301@extensions-auto-423a,1”, “CALLFILENAME=n1233799891.54–02042009-211136~0a3f0361-5350-4c15-8500-75e62ef79527~.vox”) in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: Set
– Executing [s@macro-dial:8] MixMonitor(“Local/301@extensions-auto-423a,1”, “n1233799891.54–02042009-211136~0a3f0361-5350-4c15-8500-75e62ef79527~.vox|W(1)|/usr/local/scripts/update_recording_file.pl n1233799891.54–02042009-211136~0a3f0361-5350-4c15-8500-75e62ef79527~.vox”) in new stack
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: MixMonitor
– Executing [s@macro-dial:9] GotoIf(“Local/301@extensions-auto-423a,1”, “1?dial-default:dial-to”) in new stack
– Goto (macro-dial,s,10)
== Begin MixMonitor Recording Local/301@extensions-auto-423a,1
[Feb 4 18:11:36] DEBUG[14645]: app_macro.c:337 _macro_exec: Executed application: GotoIf
– Executing [s@macro-dial:10] Dial(“Local/301@extensions-auto-423a,1”, “Zap/g2/1XXXXXXXXXX||TrH”) in new stack
[Feb 4 18:11:36] DEBUG[14645]: chan_zap.c:1948 zt_call: Dialing ‘1XXXXXXXXXX’
[Feb 4 18:11:36] DEBUG[14645]: chan_zap.c:2024 zt_call: Deferring dialing…
– Called g2/1XXXXXXXXXX
– Local/301@extensions-auto-423a,1 requested special control 20, passing it to Zap/37-1
– Local/301@extensions-auto-423a,1 requested special control 20, passing it to Zap/37-1
– Local/301@extensions-auto-423a,1 requested special control 20, passing it to Zap/37-1
== Spawn extension (macro-dial, s, 12) exited non-zero on ‘Local/301@extensions-auto-423a,2’ in macro ‘dial’
== Spawn extension (macro-dial, s, 12) exited non-zero on ‘Local/301@extensions-auto-423a,2’ in macro ‘stduser’
== Spawn extension (macro-dial, s, 12) exited non-zero on ‘Local/301@extensions-auto-423a,2’
== End MixMonitor Recording Local/301@extensions-auto-423a,2
== Executing [/usr/local/scripts/update_recording_file.pl n1233799891.55–02042009-211131~4092be89-32ab-4a34-b6c4-b462aebeb663~.vox]
[Feb 4 18:11:37] DEBUG[14645]: chan_zap.c:4377 zt_handle_event: Ignoring wink on channel 37
[Feb 4 18:11:37] DEBUG[14645]: chan_zap.c:4440 zt_handle_event: Sent deferred digit string: T1XXXXXXXXXXw
– Zap/37-1 answered IAX2/301-5970
– Hungup ‘Zap/37-1’
== Spawn extension (macro-dial, s, 10) exited non-zero on ‘IAX2/301-5970’ in macro ‘dial’
== Spawn extension (macro-dial, s, 10) exited non-zero on ‘IAX2/301-5970’
== End MixMonitor Recording Local/301@extensions-auto-423a,1
– Hungup ‘IAX2/301-5970’