[2009-12-24 23:08:46]     -- Executing [s@macro-stdexten:15] Dial("SIP/790-00000008", "SIP/701|30|tkhwTHWg") in new stack
[2009-12-24 23:08:46]     -- Called 701
[2009-12-24 23:08:46]     -- SIP/701-00000009 is ringing
[2009-12-24 23:08:48]     -- SIP/701-00000009 answered SIP/790-00000008
[2009-12-24 23:08:50] DTMF[4475]: channel.c:2351 __ast_read: DTMF begin '#' received on SIP/701-00000009
[2009-12-24 23:08:50] DTMF[4475]: channel.c:2361 __ast_read: DTMF begin passthrough '#' on SIP/701-00000009
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2283 __ast_read: DTMF end '#' received on SIP/701-00000009, duration 120 ms
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2320 __ast_read: DTMF end accepted with begin '#' on SIP/701-00000009
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2336 __ast_read: DTMF end passthrough '#' on SIP/701-00000009
[2009-12-24 23:08:51]     -- Started music on hold, class 'default', on channel 'SIP/790-00000008'
[2009-12-24 23:08:51]     -- <SIP/701-00000009> Playing 'pbx-transfer' (language 'ru')
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2351 __ast_read: DTMF begin '7' received on SIP/701-00000009
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2355 __ast_read: DTMF begin ignored '7' on SIP/701-00000009
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2283 __ast_read: DTMF end '7' received on SIP/701-00000009, duration 100 ms
[2009-12-24 23:08:51] DTMF[4475]: channel.c:2336 __ast_read: DTMF end passthrough '7' on SIP/701-00000009
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2351 __ast_read: DTMF begin '1' received on SIP/701-00000009
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2355 __ast_read: DTMF begin ignored '1' on SIP/701-00000009
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2283 __ast_read: DTMF end '1' received on SIP/701-00000009, duration 120 ms
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2336 __ast_read: DTMF end passthrough '1' on SIP/701-00000009
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2351 __ast_read: DTMF begin '0' received on SIP/701-00000009
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2355 __ast_read: DTMF begin ignored '0' on SIP/701-00000009
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2283 __ast_read: DTMF end '0' received on SIP/701-00000009, duration 120 ms
[2009-12-24 23:08:52] DTMF[4475]: channel.c:2336 __ast_read: DTMF end passthrough '0' on SIP/701-00000009
[2009-12-24 23:08:53] DTMF[4475]: channel.c:2351 __ast_read: DTMF begin '#' received on SIP/701-00000009
[2009-12-24 23:08:53] DTMF[4475]: channel.c:2355 __ast_read: DTMF begin ignored '#' on SIP/701-00000009
[2009-12-24 23:08:53] DTMF[4475]: channel.c:2283 __ast_read: DTMF end '#' received on SIP/701-00000009, duration 140 ms
[2009-12-24 23:08:53] DTMF[4475]: channel.c:2336 __ast_read: DTMF end passthrough '#' on SIP/701-00000009
[2009-12-24 23:08:53]     -- Executing [710@comlib-operator:1] Macro("Local/710@comlib-operator-8ff8,2", "stdexten|SIP|710") in new stack
...
[2009-12-24 23:08:53]     -- Executing [s@macro-stdexten:15] Dial("Local/710@comlib-operator-8ff8,2", "SIP/710|30|tkhwTHWg") in new stack
[2009-12-24 23:08:53]     -- Called 710
[2009-12-24 23:08:53]     -- SIP/710-0000000a is ringing
[2009-12-24 23:08:53]     -- Local/710@comlib-operator-8ff8,1 is ringing
[2009-12-24 23:08:54]     -- SIP/710-0000000a is ringing
[2009-12-24 23:08:55]     -- Executing [h@comlib-operator:1] ExecIf("SIP/701-00000009", "0|Set|CDR(userfield)=") in new stack
[2009-12-24 23:08:55]     -- Stopped music on hold on SIP/790-00000008
[2009-12-24 23:08:55]     -- <Local/710@comlib-operator-8ff8,1> Playing 'beep' (language 'ru')
[2009-12-24 23:08:56] DEBUG[4485]: res_monitor.c:232 ast_monitor_start: Cannot start monitoring SIP/790-00000008, already monitored
[2009-12-24 23:08:56]     -- Local/710@comlib-operator-8ff8,2 requested special control 20, passing it to SIP/710-0000000a
[2009-12-24 23:08:56]   == Spawn extension (macro-stdexten, s, 15) exited non-zero on 'Transfered/SIP/790-00000008<ZOMBIE>' in macro 'stdexten'
[2009-12-24 23:08:56]   == Spawn extension (comlib-operator, 701, 1) exited non-zero on 'Transfered/SIP/790-00000008<ZOMBIE>'
[2009-12-24 23:08:56]     -- Executing [h@comlib-operator:1] ExecIf("Transfered/SIP/790-00000008<ZOMBIE>", "1|Set|CDR(userfield)=/home/asterisk.pbxware/var/spool/asterisk/monitor/2009/12/24/23_08_46_790_701_pbx-1261685326.8") in new stack
[2009-12-24 23:08:56]     -- SIP/710-0000000a is ringing
[2009-12-24 23:09:08]     -- SIP/710-0000000a is ringing
[2009-12-24 23:09:23]     -- Nobody picked up in 30000 ms
...
[2009-12-24 23:09:23]     -- Executing [s@macro-stdexten:19] Dial("Local/710@comlib-operator-8ff8,2", "SIP/701|30|tkhwTHWg") in new stack
[2009-12-24 23:09:23]     -- Called 701
[2009-12-24 23:09:23]     -- Local/710@comlib-operator-8ff8,2 requested special control 20, passing it to SIP/701-0000000c
[2009-12-24 23:09:23]     -- Local/710@comlib-operator-8ff8,2 requested special control 20, passing it to SIP/701-0000000c
[2009-12-24 23:09:23]     -- SIP/701-0000000c is ringing
[2009-12-24 23:09:23]     -- Local/710@comlib-operator-8ff8,2 requested special control 20, passing it to SIP/701-0000000c
[2009-12-24 23:09:23]     -- Local/710@comlib-operator-8ff8,2 requested special control 20, passing it to SIP/701-0000000c
[2009-12-24 23:09:24]     -- SIP/701-0000000c is ringing
[2009-12-24 23:09:25]     -- SIP/701-0000000c answered Local/710@comlib-operator-8ff8,2
[2009-12-24 23:09:26]   == Spawn extension (macro-stdexten, s, 19) exited non-zero on 'Local/710@comlib-operator-8ff8,2' in macro 'stdexten'
[2009-12-24 23:09:26]   == Spawn extension (comlib-operator, 710, 1) exited non-zero on 'Local/710@comlib-operator-8ff8,2'