[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ABCD Rx << 0x1
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Bits changed from 0x8 to 0x0
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ABCD Tx >> [SEIZE_ACK] 0xC
[Apr 30 09:54:25] NOTICE[13403]: chan_zap.c:1282 zt_r2_on_call_init: New MFC/R2 call detected on chan 1.
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Unhandled event 9
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 0
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 0, expected length: 20
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 2 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 2
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 02, expected length: 20
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 2 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 1
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 021, expected length: 20
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 5 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 5
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 0215, expected length: 20
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 5 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 4
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 02154, expected length: 20
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [OFF]
[Apr 30 09:54:25] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 2 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 2
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 021542, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 2 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 1
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 0215421, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 0
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 02154210, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 4
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 021542104, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 7 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 7
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 0215421047, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 7 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 0
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 02154210470, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 0
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 021542104700, expected length: 20
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:26] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting DNIS digit 0
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - DNIS so far: 0215421047000, expected length: 20
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting next DNIS with signal 0x31.
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:27] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - calling callback on chan 1
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF cycle timed out, no more DNIS
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - calling callback on chan 1
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 5
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 5, expected length: 20
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 4
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 54, expected length: 20
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 2 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 2
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 542, expected length: 20
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 2 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 1
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 5421, expected length: 20
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 0
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 54210, expected length: 20
[Apr 30 09:54:47] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 4
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 542104, expected length: 20
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 4 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 7 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 7
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 5421047, expected length: 20
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 7 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 0
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 54210470, expected length: 20
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 0
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 542104700, expected length: 20
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Getting ANI digit 0
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ANI so far: 5421047000, expected length: 20
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 0 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 5 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << F [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Got end of ANI
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Requesting change to Group II with signal 0x33
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 3 [ON]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << F [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 3 [OFF]
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [ON]
[Apr 30 09:54:48] NOTICE[13403]: chan_zap.c:1308 zt_r2_on_call_offered: MFC/R2 call offered on chan 1. DNIS = 0215421047000, ANI = 5421047000, Category = National Subscriber
[Apr 30 09:54:48] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [ON]
[Apr 30 09:54:49] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Rx << 1 [OFF]
[Apr 30 09:54:49] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - MF Tx >> 1 [OFF]
[Apr 30 09:54:49] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - calling callback on chan 1
[Apr 30 09:54:49] NOTICE[13403]: chan_zap.c:1359 zt_r2_on_call_accepted: MFC/R2 call has been accepted on chan 1
[Apr 30 09:54:49]     -- Executing [0215421047000@default:1] Ringing("Zap/1-1", "") in new stack
[Apr 30 09:54:49]     -- Executing [0215421047000@default:2] Wait("Zap/1-1", "10") in new stack
[Apr 30 09:54:59]     -- Executing [0215421047000@default:3] Playback("Zap/1-1", "tt-monkeys") in new stack
[Apr 30 09:54:59] DEBUG[13403]: chan_zap.c:3912 zt_answer: Accepting MFC/R2 call on chan 1
[Apr 30 09:54:59] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ABCD Tx >> [ANSWER] 0x4
[Apr 30 09:54:59] DEBUG[13403]: chan_zap.c:1384 zt_r2_on_call_answered: MFC/R2 call has been answered on chan 1
[Apr 30 09:55:00]     -- <Zap/1-1> Playing 'tt-monkeys.gsm' (language 'en')
[Apr 30 09:55:16]     -- Executing [0215421047000@default:4] Hangup("Zap/1-1", "") in new stack
[Apr 30 09:55:16]   == Spawn extension (default, 0215421047000, 4) exited non-zero on 'Zap/1-1'
[Apr 30 09:55:16] DEBUG[13403]: chan_zap.c:3666 zt_hangup: disconnecting MFC/R2 call on chan 1
[Apr 30 09:55:16] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ABCD Tx >> [CLEAR_BACK] 0xC
[Apr 30 09:55:16]     -- Hungup 'Zap/1-1'
[Apr 30 09:55:20] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ABCD Rx << 0x9
[Apr 30 09:55:20] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Bits changed from 0x0 to 0x8
[Apr 30 09:55:20] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - Call ended
[Apr 30 09:55:20] DEBUG[13403]: chan_zap.c:1438 zt_r2_write_log: Chan 1 - ABCD Tx >> [IDLE] 0x8
[Apr 30 09:55:20] NOTICE[13403]: chan_zap.c:1334 zt_r2_on_call_end: MFC/R2 call end on chan 1