Solved SNOM PA1 / Bogen Paging

[Sully]

Good Morning!

We just installed a Snom-PA1 to integrate with a Bogen paging system using the line out interface and the aux port on the Bogen Amplifier. When we first installed and tested it worked. All of a sudden it stopped. The Snom is registered and answers, but there is no tone & audio. Before I factory reset it, I thought I'd ask a quick question.

Should I be able to do a network capture from 3cx and hear that audio being tested while making the call to the SNOM PA1? When we test we aren't able to pull the stream from the capture. We can pull other telephony calls from the capture.

Thanks!!

 

[ChrisC_3CX]

Should I be able to do a network capture from 3cx and hear that audio being tested while making the call to the SNOM PA1? When we test we aren't able to pull the stream from the capture. We can pull other telephony calls from the capture.

If the call has been answered by the PA1 then yes, you should be able to extract the audio. Provided that the device is indeed answering the call with a 200 OK, can you not see RTP traffic from the 3CX PBX to the device at all?

 

[Sully]

It's getting a 200 OK from the SNOM. Looking at the SNOM log. The 192.168.1.20 is the local SBC.


Jul 15 09:32:30.857 [DEBUG0] SIP: sip:rocess_registrar_packet: Received 200 OK

Jul 15 09:32:30.861 [INFO ] SIP: Registered at registrar as [email protected] (Expires: 1800 secs)

Jul 15 09:36:30.446 [NOTICE] SIP: RTP: set_destination adr=192.168.1.20:20004

Jul 15 09:36:30.456 [DEBUG0] SIP: Use Connection udp:192.168.1.20:5060 for packet 1000027

Jul 15 09:36:30.457 [DEBUG0] SIP: send 100 INVITE (1: MOmUX0NxAJguzEl2CcTyVA..) -> udp:192.168.1.20:5060

Jul 15 09:36:30.482 [INFO ] GUI: GUI: Allocating channel -3, line 0, current 0

Jul 15 09:36:30.493 [INFO ] GUI: find_function_key_for_uri: Looking to match sip:[email protected]:2048;line=5xvtz9yr

Jul 15 09:36:30.504 [INFO ] GUI: GUI: Line-Seize subscription was not sent

Jul 15 09:36:30.519 [DEBUG0] SIP: Use Connection udp:192.168.1.20:5060 for packet 1000028

Jul 15 09:36:30.520 [DEBUG0] SIP: send 180 INVITE (1: MOmUX0NxAJguzEl2CcTyVA..) -> udp:192.168.1.20:5060

Jul 15 09:36:30.529 [INFO ] SIP: Dialog -3/3/1 going to early

Jul 15 09:36:30.553 [INFO ] SIP: RTP: set_audible(0/0 -> 1/1): RP3 changed=1, update_req=1, adr=192.168.1.20:20004

Jul 15 09:36:30.569 [INFO ] SIP: sip::gui_connected(-3, 0, 1, 0)

Jul 15 09:36:30.582 [INFO ] GUI: speaker_mixer: 1

Jul 15 09:36:30.587 [DEBUG0] GUI: GUI: set_led: nr 1 id 1 state 1

Jul 15 09:36:30.595 [DEBUG0] PHN: Request lookup for number sip:[email protected]:5060

Jul 15 09:36:30.623 [INFO ] SIP: RTP: set_audible(1/1 -> 1/1): RP3 changed=0, update_req=0, adr=192.168.1.20:20004

Jul 15 09:36:30.653 [DEBUG0] SIP: Use Connection udp:192.168.1.20:5060 for packet 1000029

Jul 15 09:36:30.656 [DEBUG0] SIP: send 200 INVITE (1: MOmUX0NxAJguzEl2CcTyVA..) -> udp:192.168.1.20:5060

Jul 15 09:36:30.666 [INFO ] SIP: Dialog -3/3/3 going to confirmed

Jul 15 09:36:30.668 [INFO ] PHN: inca_PlayCall:0 1 1

Jul 15 09:36:30.669 [INFO ] PHN: transmit codec:0 g726_convert: 0

Jul 15 09:36:30.671 [INFO ] PHN: inca_init_channel: map stream 0 to channel 0

Jul 15 09:36:30.672 [DEBUG0] PHN: inca_init_channel: channel 0 active 1 stream: 0

Jul 15 09:36:30.673 [DEBUG0] PHN: inca_init_channel: channel 1 active 0 stream: -1

Jul 15 09:36:30.675 [INFO ] PHN: LID::inca_update_channels(1, 3)

Jul 15 09:36:30.676 [INFO ] PHN: inca_set_channels 1!

Jul 15 09:36:30.678 [INFO ] PHN: sending 'DTGOFFH' to wireless headset

Jul 15 09:36:30.679 [INFO ] PHN: inca_set_channels 1!

Jul 15 09:36:30.718 [INFO ] GUI: speaker_mixer: 1

Jul 15 09:36:30.745 [INFO ] PHN: found stream without packet ssrc, set packet ssrc 144186595

Jul 15 09:36:30.746 [NOTICE] PHN: SRTP: ssrc: 144186595 seq-nr=9963, mark: 128 rx roc=0 tx roc: 0

Jul 15 09:36:30.747 [INFO ] PHN: HandleChangesPacketSsrc: set first ssrc 144186595

Jul 15 09:36:30.763 [INFO ] PHN: inca_set_channels 1!

Jul 15 09:36:36.207 [INFO ] SIP: Dialog -3/3/4 going to terminated

Jul 15 09:36:36.223 [DEBUG0] SIP: requesting call stats: 4700 in state terminated

Jul 15 09:36:36.228 [DEBUG0] SIP: Response CallStats 4700 (2) state terminated

Jul 15 09:36:36.236 [DEBUG0] SIP: Use Connection udp:192.168.1.20:5060 for packet 1000030

Jul 15 09:36:36.237 [DEBUG0] SIP: send 200 BYE (2: MOmUX0NxAJguzEl2CcTyVA..) -> udp:192.168.1.20:5060

Jul 15 09:36:36.248 [INFO ] SIP: RTP: set_audible(1/1 -> 0/0): RP3 changed=1, update_req=0, adr=192.168.1.20:20004

Jul 15 09:36:36.249 [INFO ] SIP: RTP: close stream 1, stats 0

Jul 15 09:36:36.255 [NOTICE] SIP: RTP: set_destination RP3 adr=

Jul 15 09:36:36.273 [INFO ] GUI: synth_silent: connected lines: 0 of 1 state: Terminated inIdle: 0 inTerminated: 1

Jul 15 09:36:36.273 [INFO ] GUI: synth_silent: lines with call action: 0

Jul 15 09:36:36.273 [INFO ] GUI: synth_silent: set playstate idle and audio mode to none

Jul 15 09:36:36.274 [INFO ] GUI: speaker_mixer: 0

Jul 15 09:36:36.285 [DEBUG0] PHN: MbParser:: enter

Jul 15 09:36:36.285 [DEBUG0] PHN: MbParser:: selected_tree = /SnomIPPhoneBatch/

Jul 15 09:36:36.286 [DEBUG0] PHN: MbParser:: using cached xml

Jul 15 09:36:36.286 [DEBUG0] PHN: MbParser:: first time read -> caching xml

Jul 15 09:36:36.288 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/

Jul 15 09:36:36.288 [INFO ] PHN: Xpath contains unrecognized steps, aborting

Jul 15 09:36:36.289 [DEBUG0] PHN: MbParser:: found tree = SnomIPPhoneText

Jul 15 09:36:36.289 [DEBUG0] PHN: MbParserMainTag:: tree = SnomIPPhoneText

Jul 15 09:36:36.305 [DEBUG0] SIP: connection -3 terminated

Jul 15 09:36:36.306 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP3 changed=0, update_req=1, adr=

Jul 15 09:36:36.308 [INFO ] SIP: rtp_port::release_turn_resources(), bw_check_enabled=0, rtp relay port=0, rtcp relay port=0

Jul 15 09:36:36.309 [INFO ] SIP: RTP: set_audible(0/0 -> 0/0): RP3 changed=0, update_req=1, adr=

Jul 15 09:36:36.314 [INFO ] PHN: inca_StopCall:0

Jul 15 09:36:36.315 [DEBUG0] PHN: inca_deinit_channel: channel 0 active 0 stream: -1

Jul 15 09:36:36.316 [DEBUG0] PHN: inca_deinit_channel: channel 1 active 0 stream: -1

Jul 15 09:36:36.317 [INFO ] PHN: LID::inca_update_channels(0, 1)

Jul 15 09:36:36.318 [INFO ] PHN: inca_set_channels 0!

Jul 15 09:36:36.319 [INFO ] PHN: SetHoldToPendingStream 0 to send_ssrc: 75f79b00 packet_ssrc: 144186595

Jul 15 09:36:36.325 [DEBUG0] GUI: GUI: set_led: nr 1 id 1 state 0

Jul 15 09:36:36.383 [INFO ] PHN: inca_set_channels 0!

Jul 15 09:36:36.384 [INFO ] PHN: inca_set_channels 0!

Jul 15 09:36:36.385 [INFO ] PHN: stream_close: packet ssrc 144186595

Jul 15 09:36:36.387 [INFO ] PHN: stream_close: remove 144186595

Jul 15 09:36:37.045 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:36:38.303 [NOTICE] PHN: Fetching URL: snom://mb_exit

Jul 15 09:36:38.307 [INFO ] PHN: Deleting channel -3, identity 1, state closed, total connection count 1

Jul 15 09:36:38.321 [INFO ] GUI: synth_silent: connected lines: 0 of 0 state: Idle inIdle: 1 inTerminated: 0

Jul 15 09:36:38.322 [INFO ] GUI: synth_silent: lines with call action: 0

Jul 15 09:36:38.322 [INFO ] GUI: synth_silent: set playstate idle and audio mode to none

Jul 15 09:36:38.323 [INFO ] GUI: speaker_mixer: 0

Jul 15 09:36:38.410 [INFO ] PHN: sending 'DTGONH' to wireless headset

Jul 15 09:36:38.411 [INFO ] PHN: inca_set_channels 0!

Jul 15 09:36:38.412 [INFO ] PHN: inca_set_channels 0!

Jul 15 09:37:07.065 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:37:37.085 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:38:07.114 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:38:37.125 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:39:07.145 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:39:37.165 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:40:07.185 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:40:37.205 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:41:07.225 [INFO ] PHN: Watchdog: keep fd dog = 11

Jul 15 09:41:24.469 [INFO ] PHN: WEB: Request 69/492/0: GET /log.htm HTTP/1.1

 

[Sully]

We did test using a pair of headphones into the SNOM and that worked.

 

[ChrisC_3CX]

We did test using a pair of headphones into the SNOM and that worked.

That suggests that the PA1 is actually receiving and sending audio just fine and that you might have to take a closer look at the amplifier or whatever is connected to the PA1.

If the issue is not something obvious and you feel it might take time to identify the cause, I'd recommend that before proceeding, factory resetting the Snom PA1 just to be safe is not a bad idea especially since you can use PnP to reprovision it which makes it a very swift and easy process. Just remember that you have to disable the "SSL Transport and Ciphers" option under "Settings >> Security" in the Management Console for it to be able to provision.

 

[Sully]

Thanks Chris!! I did a hail mary and grabbed a cheap audio cable from Walmart tried using that to integrate with the PA-1 and the AMP and that seemed to work. Thanks!!

 

[ChrisC_3CX]

Excellent! Glad to hear you got this sorted!