[Mar 27 14:46:39] VERBOSE[3013] logger.c:     -- Remote UNIX connection
[Mar 27 14:46:59] VERBOSE[3046] logger.c:     -- Executing [018882378289@internal:1] Dial("SIP/46202-081f4d88", "ZAP/g0/18882378289||rTt") in new stack
[Mar 27 14:46:59] DEBUG[3046] dsp.c: dsp busy pattern set to 0,0
[Mar 27 14:46:59] DEBUG[3046] chan_zap.c: Dialing '18882378289'
[Mar 27 14:46:59] DEBUG[3046] chan_zap.c: Deferring dialing...
[Mar 27 14:46:59] VERBOSE[3046] logger.c:     -- Called g0/18882378289
[Mar 27 14:47:01] DEBUG[3046] chan_zap.c: Engaged echo training on channel 1
[Mar 27 14:47:04] DEBUG[3046] chan_zap.c: Echo cancellation already on
[Mar 27 14:47:04] VERBOSE[3046] logger.c:     -- Zap/1-1 answered SIP/46202-081f4d88
[Mar 27 14:47:10] VERBOSE[3046] logger.c:     -- Hungup 'Zap/1-1'
[Mar 27 14:47:10] VERBOSE[3046] logger.c:   == Spawn extension (internal, 018882378289, 1) exited non-zero on 'SIP/46202-081f4d88'
[Mar 27 14:47:11] VERBOSE[3070] logger.c:     -- Executing [018882378289@internal:1] Dial("SIP/46202-081f3388", "ZAP/g0/18882378289||rTt") in new stack
[Mar 27 14:47:11] DEBUG[3070] dsp.c: dsp busy pattern set to 0,0
[Mar 27 14:47:11] DEBUG[3070] chan_zap.c: Dialing '18882378289'
[Mar 27 14:47:11] DEBUG[3070] chan_zap.c: Deferring dialing...
[Mar 27 14:47:11] VERBOSE[3070] logger.c:     -- Called g0/18882378289
[Mar 27 14:47:14] DEBUG[3070] chan_zap.c: Engaged echo training on channel 2
[Mar 27 14:47:16] DEBUG[3070] chan_zap.c: Echo cancellation already on
[Mar 27 14:47:16] VERBOSE[3070] logger.c:     -- Zap/2-1 answered SIP/46202-081f3388
[Mar 27 14:47:23] VERBOSE[3070] logger.c:     -- Hungup 'Zap/2-1'
[Mar 27 14:47:23] VERBOSE[3070] logger.c:   == Spawn extension (internal, 018882378289, 1) exited non-zero on 'SIP/46202-081f3388'
[Mar 27 14:47:23] VERBOSE[3071] logger.c:     -- Executing [018882378289@internal:1] Dial("SIP/46202-081f4d88", "ZAP/g0/18882378289||rTt") in new stack
[Mar 27 14:47:23] DEBUG[3071] dsp.c: dsp busy pattern set to 0,0
[Mar 27 14:47:23] DEBUG[3071] chan_zap.c: Dialing '18882378289'
[Mar 27 14:47:23] DEBUG[3071] chan_zap.c: Deferring dialing...
[Mar 27 14:47:23] VERBOSE[3071] logger.c:     -- Called g0/18882378289
[Mar 27 14:47:24] DEBUG[3071] chan_zap.c: Ignore switch to REVERSED Polarity on channel 1, state 3
[Mar 27 14:47:24] DEBUG[3071] chan_zap.c: Ignoring Polarity switch to IDLE on channel 1, state 3
[Mar 27 14:47:24] DEBUG[3071] chan_zap.c: Polarity Reversal event occured - DEBUG 2: channel 1, state 3, pol= 0, aonp= 0, honp= 0, pdelay= 600, tv= -242166025
[Mar 27 14:47:24] DEBUG[3071] chan_zap.c: Ignore switch to REVERSED Polarity on channel 1, state 3
[Mar 27 14:47:24] DEBUG[3071] chan_zap.c: Ignoring Polarity switch to IDLE on channel 1, state 3
[Mar 27 14:47:24] DEBUG[3071] chan_zap.c: Polarity Reversal event occured - DEBUG 2: channel 1, state 3, pol= 0, aonp= 0, honp= 0, pdelay= 600, tv= -242165833
[Mar 27 14:47:26] DEBUG[3071] chan_zap.c: Engaged echo training on channel 1
[Mar 27 14:47:28] DEBUG[3071] chan_zap.c: Echo cancellation already on
[Mar 27 14:47:28] VERBOSE[3071] logger.c:     -- Zap/1-1 answered SIP/46202-081f4d88
[Mar 27 14:47:32] VERBOSE[3071] logger.c:     -- Hungup 'Zap/1-1'
[Mar 27 14:47:32] VERBOSE[3071] logger.c:   == Spawn extension (internal, 018882378289, 1) exited non-zero on 'SIP/46202-081f4d88'