##### Complements Debug Concole: ##### [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 49 - calling callback on chan 49 [Aug 24 02:18:11] WARNING[28324]: chan_zap.c:1143 zt_r2_write_log: Chan 49 - Seize Timeout Expired! [Aug 24 02:18:11] ERROR[28324]: chan_zap.c:1146 zt_r2_write_log: Chan 49 - Protocol error. Reason = Seize Timeout, R2 State = Seize Transmitted, MF state = MF Engine Off, MF Group = Forward MF init [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 49 - DNIS = 276, ANI = , Last MF Signal = [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 49 - ABCD Tx >> [IDLE] 0x08 [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 49 - ABCD Raw Tx >> 0x09 [Aug 24 02:18:11] ERROR[28324]: chan_zap.c:959 zt_r2_on_protocol_error: MFC/R2 protocol error on chan 49: Seize Timeout [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:3005 zt_hangup: disconnecting MFC/R2 call on chan 49 -- Hungup 'Zap/49-1' -- Executing [failed@rec-r2:1] Answer("OutgoingSpoolFailed", "") in new stack == Spawn extension (rec-r2, failed, 1) exited non-zero on 'OutgoingSpoolFailed' -- Executing [h@rec-r2:1] Hangup("OutgoingSpoolFailed", "") in new stack == Spawn extension (rec-r2, h, 1) exited non-zero on 'OutgoingSpoolFailed' [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:918 zt_r2_get_channel_category: no MFC/R2 category specified for chan Zap/48-1, using default National Subscriber [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 48 - Attempting to make call (ANI=, DNIS=239, category=National Subscriber) [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 48 - ABCD Raw Rx << 0x09 [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 48 - No change in bits [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 48 - Call started at Sun Aug 24 02:18:11 2008 on chan 48 [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 48 - ABCD Tx >> [SEIZE] 0x00 [Aug 24 02:18:11] DEBUG[28324]: chan_zap.c:1152 zt_r2_write_log: Chan 48 - ABCD Raw Tx >> 0x01 ##### Sometimes on Channel Hangup i has this error on channels Hangup: ##### -- Executing [h@incoming:1] Hangup("Zap/22-1", "") in new stack == Spawn extension (incoming, h, 1) exited non-zero on 'Zap/22-1' == End MixMonitor Recording Zap/22-1 [Aug 24 01:48:11] DEBUG[24447]: chan_zap.c:3005 zt_hangup: disconnecting MFC/R2 call on chan 22 [Aug 24 01:48:11] DEBUG[24447]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - Call ended [Aug 24 01:48:11] DEBUG[24447]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - ABCD Tx >> [IDLE] 0x08 [Aug 24 01:48:11] DEBUG[24447]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - ABCD Raw Tx >> 0x09 [Aug 24 01:48:11] NOTICE[24447]: chan_zap.c:1009 zt_r2_on_call_end: MFC/R2 call end on chan 22 [Aug 24 01:48:11] DEBUG[24447]: chan_zap.c:1021 zt_r2_update_monitor_count: At least one device needs monitoring, let's wake up that lazy bastard. -- Hungup 'Zap/22-1' == Parsing '/etc/asterisk/manager.conf': Found [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:918 zt_r2_get_channel_category: no MFC/R2 category specified for chan Zap/22-1, using default National Subscriber [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - Attempting to make call (ANI=, DNIS=204, category=National Subscriber) [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - ABCD Raw Rx << 0x09 [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - No change in bits [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - Call started at Sun Aug 24 01:48:11 2008 on chan 22 [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - ABCD Tx >> [SEIZE] 0x00 [Aug 24 01:48:11] DEBUG[24642]: chan_zap.c:1152 zt_r2_write_log: Chan 22 - ABCD Raw Tx >> 0x01 [Aug 24 01:48:11] DEBUG[23079]: chan_zap.c:8610 mfcr2_monitor: Alright, back to work! [Aug 24 01:48:11] DEBUG[23081]: chan_zap.c:1152 zt_r2_write_log: Chan 53 - ABCD Raw Rx << 0x01 [Aug 24 01:48:11] DEBUG[23081]: chan_zap.c:1152 zt_r2_write_log: Chan 53 - Bits changed from 0x04 to 0x00 [Aug 24 01:48:11] DEBUG[23081]: chan_zap.c:1152 zt_r2_write_log: Chan 53 - ABCD Rx << [0x00] 0x00 [Aug 24 01:48:11] ERROR[23081]: chan_zap.c:1146 zt_r2_write_log: Chan 53 - Protocol error. Reason = Invalid CAS, R2 State = Clear Forward Transmitted, MF state = MF Engine Off, MF Group = Forward Group II [Aug 24 01:48:11] DEBUG[23081]: chan_zap.c:1152 zt_r2_write_log: Chan 53 - DNIS = 80, ANI = , Last MF Signal = [Aug 24 01:48:11] DEBUG[23081]: chan_zap.c:1152 zt_r2_write_log: Chan 53 - ABCD Tx >> [IDLE] 0x08 [Aug 24 01:48:11] DEBUG[23081]: chan_zap.c:1152 zt_r2_write_log: Chan 53 - ABCD Raw Tx >> 0x09 [Aug 24 01:48:11] ERROR[23081]: chan_zap.c:959 zt_r2_on_protocol_error: MFC/R2 protocol error on chan 53: Invalid CAS ##### Cli Log - Channel 40 ( E1-2 ) calling Channel 9 ( E1-1 ) ##### [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:8610 mfcr2_monitor: Alright, back to work! [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Raw Rx << 0x09 [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - Bits changed from 0x00 to 0x08 [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Rx << [CLEAR FORWARD] 0x08 [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - Call ended [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Tx >> [IDLE] 0x08 [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Raw Tx >> 0x09 [Aug 25 10:09:22] NOTICE[3214]: chan_zap.c:1009 zt_r2_on_call_end: MFC/R2 call end on chan 40 [Aug 25 10:09:22] DEBUG[6306]: chan_zap.c:918 zt_r2_get_channel_category: no MFC/R2 category specified for chan Zap/40-1, using default National Subscriber [Aug 25 10:09:22] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - Attempting to make call (ANI=, DNIS=544, category=National Subscriber) [Aug 25 10:09:22] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Raw Rx << 0x09 [Aug 25 10:09:22] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - No change in bits [Aug 25 10:09:22] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Tx >> [SEIZE] 0x00 [Aug 25 10:09:22] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Raw Tx >> 0x01 [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - ABCD Raw Rx << 0x01 [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - Bits changed from 0x0C to 0x00 [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - ABCD Rx << [0x00] 0x00 [Aug 25 10:09:22] ERROR[3212]: chan_zap.c:1146 zt_r2_write_log: Chan 9 - Protocol error. Reason = Invalid CAS, R2 State = Clear Forward Transmitted, MF state = MF Engine Off, MF Group = Forward Group II [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - DNIS = 566, ANI = , Last MF Signal = [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - ABCD Tx >> [IDLE] 0x08 [Aug 25 10:09:22] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - ABCD Raw Tx >> 0x09 [Aug 25 10:09:22] ERROR[3212]: chan_zap.c:959 zt_r2_on_protocol_error: MFC/R2 protocol error on chan 9: Invalid CAS [Aug 25 10:09:22] DEBUG[3214]: chan_zap.c:8608 mfcr2_monitor: No one requires my monitoring services :-( [Aug 25 10:09:30] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - calling callback on chan 40 [Aug 25 10:09:30] WARNING[6306]: chan_zap.c:1143 zt_r2_write_log: Chan 40 - Seize Timeout Expired! [Aug 25 10:09:30] ERROR[6306]: chan_zap.c:1146 zt_r2_write_log: Chan 40 - Protocol error. Reason = Seize Timeout, R2 State = Seize Transmitted, MF state = MF Engine Off, MF Group = Forward MF init [Aug 25 10:09:30] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - DNIS = 544, ANI = , Last MF Signal = [Aug 25 10:09:30] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Tx >> [IDLE] 0x08 [Aug 25 10:09:30] DEBUG[6306]: chan_zap.c:1152 zt_r2_write_log: Chan 40 - ABCD Raw Tx >> 0x09 [Aug 25 10:09:30] ERROR[6306]: chan_zap.c:959 zt_r2_on_protocol_error: MFC/R2 protocol error on chan 40: Seize Timeout [Aug 25 10:09:30] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - ABCD Raw Rx << 0x09 [Aug 25 10:09:30] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - Bits changed from 0x00 to 0x08 [Aug 25 10:09:30] DEBUG[3212]: chan_zap.c:1152 zt_r2_write_log: Chan 9 - ABCD Rx << [IDLE] 0x08 [Aug 25 10:09:30] NOTICE[3212]: chan_zap.c:1168 zt_r2_on_line_idle: Far end unblocked on chan 9 [Aug 25 10:09:30] DEBUG[6306]: chan_zap.c:3005 zt_hangup: disconnecting MFC/R2 call on chan 40 [Aug 25 10:09:30] DEBUG[6306]: chan_zap.c:1021 zt_r2_update_monitor_count: At least one device needs monitoring, let's wake up that lazy bastard. ##### Call Log File - Channel 40 ( E1-2 ) calling Channel 9 ( E1-1 ) ##### [09:50:38:772] [Thread: 3025005488] [Chan 9] - Call started at Mon Aug 25 09:50:38 2008 on chan 9 [09:50:38:772] [Thread: 3025005488] [Chan 9] - ABCD Tx >> [SEIZE] 0x00 [09:50:38:772] [Thread: 3025005488] [Chan 9] - ABCD Raw Tx >> 0x01 [09:50:38:789] [Thread: 3074022320] [Chan 9] - ABCD Raw Rx << 0x0D [09:50:38:789] [Thread: 3074022320] [Chan 9] - Bits changed from 0x08 to 0x0C [09:50:38:789] [Thread: 3074022320] [Chan 9] - ABCD Rx << [SEIZE ACK] 0x0C [09:50:38:789] [Thread: 3074022320] [Chan 9] - Sending DNIS digit 4 [09:50:38:789] [Thread: 3074022320] [Chan 9] - MF Tx >> 4 [ON] [09:50:38:852] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [ON] [09:50:38:852] [Thread: 3025005488] [Chan 9] - MF Tx >> 4 [OFF] [09:50:38:912] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [OFF] [09:50:38:912] [Thread: 3025005488] [Chan 9] - Sending DNIS digit 1 [09:50:38:912] [Thread: 3025005488] [Chan 9] - MF Tx >> 1 [ON] [09:50:38:972] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [ON] [09:50:38:972] [Thread: 3025005488] [Chan 9] - MF Tx >> 1 [OFF] [09:50:39:032] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [OFF] [09:50:39:032] [Thread: 3025005488] [Chan 9] - Sending DNIS digit 2 [09:50:39:032] [Thread: 3025005488] [Chan 9] - MF Tx >> 2 [ON] [09:50:39:072] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [ON] [09:50:39:072] [Thread: 3025005488] [Chan 9] - MF Tx >> 2 [OFF] [09:50:39:132] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [OFF] [09:50:39:132] [Thread: 3025005488] [Chan 9] - No more DNIS. Doing nothing, waiting for timeout. [09:50:40:612] [Thread: 3025005488] [Chan 9] - MF Rx << 5 [ON] [09:50:40:752] [Thread: 3025005488] [Chan 9] - MF Rx << 5 [OFF] [09:50:40:752] [Thread: 3025005488] [Chan 9] - Sending category National Subscriber [09:50:40:752] [Thread: 3025005488] [Chan 9] - MF Tx >> 1 [ON] [09:50:40:812] [Thread: 3025005488] [Chan 9] - MF Rx << 5 [ON] [09:50:40:812] [Thread: 3025005488] [Chan 9] - MF Tx >> 1 [OFF] [09:50:40:892] [Thread: 3025005488] [Chan 9] - MF Rx << 5 [OFF] [09:50:40:892] [Thread: 3025005488] [Chan 9] - Sending more ANI unavailable [09:50:40:892] [Thread: 3025005488] [Chan 9] - MF Tx >> F [ON] [09:50:40:952] [Thread: 3025005488] [Chan 9] - MF Rx << 3 [ON] [09:50:40:952] [Thread: 3025005488] [Chan 9] - MF Tx >> F [OFF] [09:50:41:012] [Thread: 3025005488] [Chan 9] - MF Rx << 3 [OFF] [09:50:41:012] [Thread: 3025005488] [Chan 9] - Sending category National Subscriber [09:50:41:012] [Thread: 3025005488] [Chan 9] - MF Tx >> 1 [ON] [09:50:41:072] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [ON] [09:50:41:072] [Thread: 3025005488] [Chan 9] - MF Tx >> 1 [OFF] [09:50:41:132] [Thread: 3025005488] [Chan 9] - MF Rx << 1 [OFF] [09:50:41:269] [Thread: 3025005488] [Chan 9] - ABCD Raw Rx << 0x05 [09:50:41:269] [Thread: 3025005488] [Chan 9] - Bits changed from 0x0C to 0x04 [09:50:41:269] [Thread: 3025005488] [Chan 9] - ABCD Rx << [ANSWER] 0x04 [09:57:55:861] [Thread: 3011931056] [Chan 9] - ABCD Raw Rx << 0x0D [09:57:55:861] [Thread: 3011931056] [Chan 9] - Bits changed from 0x04 to 0x0C [09:57:55:861] [Thread: 3011931056] [Chan 9] - ABCD Rx << [CLEAR BACK] 0x0C [09:57:55:861] [Thread: 3011931056] [Chan 9] - Far end disconnected. Reason: Normal Clearing [09:57:55:877] [Thread: 3011931056] [Chan 9] - ABCD Tx >> [CLEAR FORWARD] 0x08 [09:57:55:877] [Thread: 3011931056] [Chan 9] - ABCD Raw Tx >> 0x09 [09:57:55:909] [Thread: 3074022320] [Chan 9] - ABCD Raw Rx << 0x09 [09:57:55:909] [Thread: 3074022320] [Chan 9] - Bits changed from 0x0C to 0x08 [09:57:55:909] [Thread: 3074022320] [Chan 9] - ABCD Rx << [IDLE] 0x08 [09:57:55:909] [Thread: 3074022320] [Chan 9] - Call ended