[Freeswitch-users] different behaviour on uuid_bridge, doesn't really bridge

[Peter P GMX]

I have setup a test machine and a production machine. Since recently the
production machine behaves differently in terms of uuid bridge.

How it should work (and how it worked before)
  1) call A comes in
  2) I play some messages to A
  3) In the meantime I originate a call to B and transfer to an
extension, where also some messages are played
  4) Then I bridge A and B, so they are dropped off the current
announcements an speak to each other
  5) when either A or B hangs up, both legs are terminated

New behaviour
  1) call A comes in
  2) I play some messages to A
  3) In the meantime I originate a call to B and transfer to an
extension, where also some messages are played
  4) Then I bridge A and B, A and B can hear each other for 1/2 sec,
then A constinues to hear its messages, B does not hear anything
  5) when either A or B hangs up, both legs are terminated

4) is different now!

The FS console show some messages about unbridge
(SWITCH_MESSAGE_INDICATE_UNBRIDGE), but somehow the calls are still
connected until A or B hangs up.

Anybody has a clue?
Best regards
Peter

3) is finished, 4) starts
2009-01-06 17:35:31 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:35:31 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:35:31 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:35:34 [DEBUG] switch_ivr_play_say.c:1222
switch_ivr_play_file() done playing file
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:1094
switch_ivr_uuid_bridge() (OpenZAP/2:3/49171xxxxxxx) State Change
CS_EXECUTE -> CS_RESET
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:1095
switch_ivr_uuid_bridge() (OpenZAP/2:1/216xxxxx) State Change CS_EXECUTE
-> CS_RESET
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_ivr_play_say.c:1222
switch_ivr_play_file() done playing file
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:433
switch_core_session_run() (OpenZAP/2:1/216xxxxx) State EXECUTE going to
sleep
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:375
switch_core_session_run() (OpenZAP/2:1/216xxxxx) Running State Change
CS_RESET
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:429
switch_core_session_run() (OpenZAP/2:1/216xxxxx) State RESET
4) Start
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:527
uuid_bridge_on_reset() OpenZAP/2:1/216xxxxx CUSTOM RESET
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:53
switch_core_standard_on_reset() OpenZAP/2:1/216xxxxx Standard RESET
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:429
switch_core_session_run() (OpenZAP/2:1/216xxxxx) State RESET going to sleep
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:433
switch_core_session_run() (OpenZAP/2:3/49171xxxxxxx) State EXECUTE going
to sleep
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:375
switch_core_session_run() (OpenZAP/2:3/49171xxxxxxx) Running State
Change CS_RESET
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:429
switch_core_session_run() (OpenZAP/2:3/49171xxxxxxx) State RESET
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:527
uuid_bridge_on_reset() OpenZAP/2:3/49171xxxxxxx CUSTOM RESET
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:533
uuid_bridge_on_reset() (OpenZAP/2:3/49171xxxxxxx) State Change CS_RESET
-> CS_SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:429
switch_core_session_run() (OpenZAP/2:3/49171xxxxxxx) State RESET going
to sleep
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:375
switch_core_session_run() (OpenZAP/2:3/49171xxxxxxx) Running State
Change CS_SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:439
switch_core_session_run() (OpenZAP/2:3/49171xxxxxxx) State SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] mod_openzap.c:517 channel_on_soft_execute()
CHANNEL SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:545
uuid_bridge_on_soft_execute() OpenZAP/2:3/49171xxxxxxx CUSTOM TRANSMIT
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:571
uuid_bridge_on_soft_execute() (OpenZAP/2:1/216xxxxx) State Change
CS_RESET -> CS_SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:375
switch_core_session_run() (OpenZAP/2:1/216xxxxx) Running State Change
CS_SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:439
switch_core_session_run() (OpenZAP/2:1/216xxxxx) State SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] mod_openzap.c:517 channel_on_soft_execute()
CHANNEL SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:545
uuid_bridge_on_soft_execute() OpenZAP/2:1/216xxxxx CUSTOM TRANSMIT
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:181
switch_core_standard_on_soft_execute() OpenZAP/2:1/216xxxxx Standard
SOFT_EXECUTE
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:439
switch_core_session_run() (OpenZAP/2:1/216xxxxx) State SOFT_EXECUTE
going to sleep
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:814
switch_ivr_multi_threaded_bridge() (OpenZAP/2:1/216xxxxx) State Change
CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:856
switch_ivr_multi_threaded_bridge() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:863
switch_ivr_multi_threaded_bridge() OpenZAP/2:3/49171xxxxxxx receive
message [SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:667
switch_core_session_queue_private_event() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_ivr_bridge.c:907
switch_ivr_multi_threaded_bridge() (OpenZAP/2:1/216xxxxx) State Change
CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2009-01-06 17:35:35 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:375
switch_core_session_run() (OpenZAP/2:1/216xxxxx) Running State Change
CS_EXCHANGE_MEDIA
2009-01-06 17:35:35 [DEBUG] switch_core_state_machine.c:436
switch_core_session_run() (OpenZAP/2:1/216xxxxx) State EXCHANGE_MEDIA
2009-01-06 17:35:35 [DEBUG] mod_openzap.c:511
channel_on_exchange_media() CHANNEL EXCHANGE_MEDIA
2009-01-06 17:35:36 [DEBUG] switch_core_session.c:667
switch_core_session_queue_private_event() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:36 [DEBUG] switch_ivr_bridge.c:226
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_UNBRIDGE]
2009-01-06 17:35:36 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:36 [DEBUG] switch_ivr.c:391 switch_ivr_parse_event()
OpenZAP/2:1/216xxxxx Command Execute read(0 1
custom/warteschleife_30.wav interrupt_digit 0 )
2009-01-06 17:35:36 [DEBUG] switch_ivr_play_say.c:932
switch_ivr_play_file() Codec Activated L16 at 8000hz 1 channels 20ms
2009-01-06 17:35:36 [DEBUG] switch_core_io.c:610
switch_core_session_write_frame() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_TRANSCODING_NECESSARY]
2009-01-06 17:35:36 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:36 [DEBUG] switch_ivr_bridge.c:226
audio_bridge_thread() OpenZAP/2:3/49171xxxxxxx receive message
[SWITCH_MESSAGE_INDICATE_UNBRIDGE]
2009-01-06 17:35:36 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:36 [DEBUG] switch_ivr.c:391 switch_ivr_parse_event()
OpenZAP/2:3/49171xxxxxxx Command Execute stop_dtmf(true)
2009-01-06 17:35:36 [DEBUG] switch_ivr_bridge.c:229
audio_bridge_thread() OpenZAP/2:3/49171xxxxxxx receive message
[SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:35:36 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:36 [DEBUG] switch_ivr_bridge.c:231
audio_bridge_thread() Send signal OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:37 [DEBUG] switch_core_session.c:667
switch_core_session_queue_private_event() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:37 [DEBUG] switch_ivr_bridge.c:226
audio_bridge_thread() OpenZAP/2:3/49171xxxxxxx receive message
[SWITCH_MESSAGE_INDICATE_UNBRIDGE]
2009-01-06 17:35:37 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:37 [DEBUG] switch_ivr_bridge.c:229
audio_bridge_thread() OpenZAP/2:3/49171xxxxxxx receive message
[SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:35:37 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:35:37 [DEBUG] switch_ivr_bridge.c:231
audio_bridge_thread() Send signal OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:35:46 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 31
--------------------------------------------------------------------------------
[08 02 80 04 62 1c 18 91 a1 15 02 01 7d 02 01 22 30 0d a1 05 30 03 02 01
08 82 01 00 83 01 00]

2009-01-06 17:35:46 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:35:46 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:35:46 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:36:02 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 31
--------------------------------------------------------------------------------
[08 02 80 04 62 1c 18 91 a1 15 02 01 7e 02 01 22 30 0d a1 05 30 03 02 01
09 82 01 00 83 01 00]

2009-01-06 17:36:02 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:36:02 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:36:02 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:36:06 [DEBUG] switch_ivr_play_say.c:1222
switch_ivr_play_file() done playing file
2009-01-06 17:36:07 [DEBUG] switch_ivr_bridge.c:229
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:36:07 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:07 [DEBUG] switch_ivr_bridge.c:231
audio_bridge_thread() Send signal OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:36:08 [DEBUG] switch_core_session.c:667
switch_core_session_queue_private_event() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:08 [DEBUG] switch_ivr_bridge.c:226
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_UNBRIDGE]
2009-01-06 17:36:08 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:08 [DEBUG] switch_ivr.c:391 switch_ivr_parse_event()
OpenZAP/2:1/216xxxxx Command Execute read(0 1
custom/warteschleife_30.wav interrupt_digit 0 )
2009-01-06 17:36:08 [DEBUG] switch_ivr_play_say.c:932
switch_ivr_play_file() Codec Activated L16 at 8000hz 1 channels 20ms
2009-01-06 17:36:08 [DEBUG] switch_core_io.c:610
switch_core_session_write_frame() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_TRANSCODING_NECESSARY]
2009-01-06 17:36:08 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:17 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 31
--------------------------------------------------------------------------------
[08 02 80 04 62 1c 18 91 a1 15 02 01 7f 02 01 22 30 0d a1 05 30 03 02 01
0a 82 01 00 83 01 00]

2009-01-06 17:36:17 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:36:17 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:36:17 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:36:33 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 32
--------------------------------------------------------------------------------
[08 02 80 04 62 1c 19 91 a1 16 02 02 00 80 02 01 22 30 0d a1 05 30 03 02
01 0b 82 01 00 83 01 00]

2009-01-06 17:36:33 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:36:33 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:36:33 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:36:38 [DEBUG] switch_ivr_play_say.c:1222
switch_ivr_play_file() done playing file
2009-01-06 17:36:39 [DEBUG] switch_ivr_bridge.c:229
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:36:39 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:39 [DEBUG] switch_ivr_bridge.c:231
audio_bridge_thread() Send signal OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:36:40 [DEBUG] switch_core_session.c:667
switch_core_session_queue_private_event() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:40 [DEBUG] switch_ivr_bridge.c:226
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_UNBRIDGE]
2009-01-06 17:36:40 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:40 [DEBUG] switch_ivr.c:391 switch_ivr_parse_event()
OpenZAP/2:1/216xxxxx Command Execute read(0 1
custom/dieser_berater_ist_leider_zur_zeit_nicht_erreichbar_bitte_waehlen_sie_einen_ande.wav
interrupt_digit 0 )
2009-01-06 17:36:40 [DEBUG] switch_ivr_play_say.c:932
switch_ivr_play_file() Codec Activated L16 at 8000hz 1 channels 20ms
2009-01-06 17:36:40 [DEBUG] switch_core_io.c:610
switch_core_session_write_frame() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_TRANSCODING_NECESSARY]
2009-01-06 17:36:40 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:45 [DEBUG] switch_ivr_play_say.c:1222
switch_ivr_play_file() done playing file
2009-01-06 17:36:46 [DEBUG] switch_ivr_bridge.c:229
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_BRIDGE]
2009-01-06 17:36:46 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:46 [DEBUG] switch_ivr_bridge.c:231
audio_bridge_thread() Send signal OpenZAP/2:3/49171xxxxxxx [BREAK]
2009-01-06 17:36:47 [DEBUG] switch_core_session.c:667
switch_core_session_queue_private_event() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:47 [DEBUG] switch_ivr_bridge.c:226
audio_bridge_thread() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_UNBRIDGE]
2009-01-06 17:36:47 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:47 [DEBUG] switch_ivr.c:391 switch_ivr_parse_event()
OpenZAP/2:1/216xxxxx Command Execute read(0 4
custom/geben_sie_bitte_nach_dem_signalton_die_kennung_des_gewuenschten_beraters_ein_sch2.wav
dtmfdtmf 10000 #,*)
2009-01-06 17:36:47 [DEBUG] switch_ivr_play_say.c:932
switch_ivr_play_file() Codec Activated L16 at 8000hz 1 channels 20ms
2009-01-06 17:36:47 [DEBUG] switch_core_io.c:610
switch_core_session_write_frame() OpenZAP/2:1/216xxxxx receive message
[SWITCH_MESSAGE_INDICATE_TRANSCODING_NECESSARY]
2009-01-06 17:36:47 [DEBUG] switch_core_session.c:489
switch_core_session_perform_receive_message() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:36:48 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 32
--------------------------------------------------------------------------------
[08 02 80 04 62 1c 19 91 a1 16 02 02 00 81 02 01 22 30 0d a1 05 30 03 02
01 0c 82 01 00 83 01 00]

2009-01-06 17:36:48 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:36:48 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:36:48 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:37:03 [DEBUG] switch_ivr_play_say.c:1222
switch_ivr_play_file() done playing file
2009-01-06 17:37:04 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 32
--------------------------------------------------------------------------------
[08 02 80 04 62 1c 19 91 a1 16 02 02 00 82 02 01 22 30 0d a1 05 30 03 02
01 0d 82 01 00 83 01 00]

2009-01-06 17:37:04 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[62] Size:[103] CRV: 4 (0x4, CTX: Terminator)
2009-01-06 17:37:04 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
783bc0 (2:3) source isdn_data->channels_local_crv[0x4]
2009-01-06 17:37:04 [CRIT] ozmod_isdn.c:760 zap_isdn_931_34() Received
unhandled message 98 (0x62)
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:777 zap_isdn_921_23() READ 13
--------------------------------------------------------------------------------
[08 02 00 35 45 08 02 80 90 1e 02 82 88]

5) Hangup
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:320 zap_isdn_931_34() Yay I got
an event! Type:[45] Size:[115] CRV: 53 (0x35, CTX: Originator)
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:352 zap_isdn_931_34() zchan
77df70 (2:1) source isdn_data->channels_remote_crv[0x35]
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:455 zap_isdn_931_34() Changing
state on 2:1 from UP to TERMINATING
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:813 state_advance() 2:1 STATE
[TERMINATING]
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:1121 state_advance()
Terminating: Direction Inbound
2009-01-06 17:37:12 [DEBUG] mod_openzap.c:1418 on_clear_channel_signal()
got clear channel sig [STOP]
2009-01-06 17:37:12 [NOTICE] mod_openzap.c:1437
on_clear_channel_signal() Hangup OpenZAP/2:1/216xxxxx
[CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
2009-01-06 17:37:12 [DEBUG] switch_channel.c:1441
switch_channel_perform_hangup() Send signal OpenZAP/2:1/216xxxxx [KILL]
2009-01-06 17:37:12 [DEBUG] switch_core_session.c:779
switch_core_session_signal_state_change() Send signal
OpenZAP/2:1/216xxxxx [BREAK]
2009-01-06 17:37:12 [DEBUG] ozmod_isdn.c:1529 q931_rx_32() WRITE 5