Omgeving: Snom370/8.7.3.25.5 achter Arcadyan VGV7519 met Firmware Version:02.00.125W4 (11.01.2013-11:01:22).
Op dit Telfort VDSL modem zijn een vast IP adres ingesteld voor het MAC adres van de Snom en zijn de navolgende poorten doorgestuurd:
1. TCP & UDP 5060
2. UDP 10000-11000
In de log van de Snom is kenmerkend ide foutmelding transaction_timeout udp: 1002386 (udp:185.29.203.27:5060) op het einde.
Enige vermoedens waar deze storing mogelijk door veroorzaakt wordt?
===
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: mark for deletion 35/B2B.11.8190333, cbi 0, 0
25/7/2014 11:54:48 [DEBUG1] PHN: Goto Best State from 'Connected' (12), force 1
25/7/2014 11:54:48 [DEBUG1] GUI: SetSynthMode 3 -> 0, audio 2, force 0
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Dest 0
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Volume 0 ()
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Mixer 0
25/7/2014 11:54:48 [DEBUG1] GUI: PlaySynth PLAY 0 0 0
25/7/2014 11:54:48 [DEBUG1] PHN: Goto Best State from 'Terminated' (15), force 1
25/7/2014 11:54:48 [DEBUG1] GUI: set_audio_device: early offhook: incoming rings 0
25/7/2014 11:54:48 [DEBUG0] CFG: apply_value: outgoing_identity = '5', set.need_apply: 0, finished: 1, need reboot to apply: 0
25/7/2014 11:54:48 [DEBUG1] GUI: SetSynthMode 0 -> 0, audio 1, force 1
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Dest 0
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Volume 0 ()
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Mixer 0
25/7/2014 11:54:48 [DEBUG1] GUI: PlaySynth PLAY 0 0 0
25/7/2014 11:54:48 [DEBUG1] GUI: set_audio_device: early offhook: incoming rings 0
25/7/2014 11:54:48 [DEBUG0] PHN: SIP: Response CallStats 284197 (1) state terminated: Rx Total_Rx_Pkts=0,Rx_Pkts=0,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0, Tx Total_Tx_Pkts=268,Tx_Pkts=268,Remote_Tx_Pkts=0
25/7/2014 11:54:48 [INFO ] PHN: RTP: set_audible(0/0 -> 0/0): RP35 changed=0, update_req=0, adr=185.29.203.27:53316
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: Routing to outbound proxy: sip:sip.cheapconnect.net
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=url ? sip:sip.cheapconnect.net all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=srv tls _sips._tcp.sip.cheapconnect.net all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=srv tcp _sip._tcp.sip.cheapconnect.net all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=srv udp _sip._udp.sip.cheapconnect.net all 6
25/7/2014 11:54:48 [DEBUG1] GUI: SetSynthMode 0 -> 0, audio 0, force 0
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Dest 0
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Volume 0 ()
25/7/2014 11:54:48 [DEBUG1] GUI: Speaker Mixer 0
25/7/2014 11:54:48 [DEBUG1] GUI: PlaySynth PLAY 0 0 0
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=srv udp _sip._udp.sip.cheapconnect.net all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=a udp srv01.cheapconnect.net 5060 all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=a udp srv01.cheapconnect.net 5060 all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: route pending packet 1002387: entry=udp 185.29.203.27 5060 all 6
25/7/2014 11:54:48 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:54:48 [DEBUG0] GUI: GUI: set_led: nr 4 id 2 state 0
25/7/2014 11:54:50 [ALERT ] LID: inca360_handle_kb: key event: 152572710
25/7/2014 11:54:50 [DEBUG1] LID: handle_next_key_events: key time: 152572710 last time: 152570320
25/7/2014 11:54:50 [DEBUG1] LID: handle_next_key_events: play key
25/7/2014 11:54:50 [DEBUG1] LID: handle_next_key_events: set last key event time: 152572710
25/7/2014 11:54:50 [ALERT ] LID: inca360_handle_kb: key event: 152572860
25/7/2014 11:54:50 [DEBUG1] LID: handle_next_key_events: key time: 152572860 last time: 152572710
25/7/2014 11:54:50 [DEBUG1] LID: handle_next_key_events: play key
25/7/2014 11:54:50 [ERROR ] LID: Unknown DTMF key code: 61
25/7/2014 11:54:50 [DEBUG0] TOOLS: Uri Replace >../< in >xml/gui/call_lists_list.xml< with ><
25/7/2014 11:54:50 [DEBUG0] TOOLS: Uri Replaced result: >file:///xml/gui/call_lists_list.xml<
25/7/2014 11:54:50 [INFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
25/7/2014 11:54:50 [INFO ] PHN: Xpath contains unrecognized steps, aborting
25/7/2014 11:54:50 [INFO ] PHN: Xpath applies: /*/*
25/7/2014 11:54:50 [DEBUG0] PHN: Xpath succesfully fetched: /*/*
25/7/2014 11:54:50 [INFO ] PHN: Xpath applies: /*
25/7/2014 11:54:50 [DEBUG0] PHN: Xpath succesfully fetched: /*
25/7/2014 11:54:50 [DEBUG1] LID: handle_next_key_events: set last key event time: 152572860
25/7/2014 11:54:50 [DEBUG0] PHN: TaskMon: LCS/460682 PollEvent took 330 msecs, started 09:54:50.566
25/7/2014 11:54:50 [DEBUG0] PHN: LoopMon: LCS/460682 took 340 (60) msecs, started 09:54:50.560, 2 tasks
25/7/2014 11:54:50 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:54:52 [DEBUG0] GUI: GUI: set_led: nr 2 id 15 state 0
25/7/2014 11:54:52 [DEBUG0] GUI: GUI: set_led: nr 0 id 13 state 0
25/7/2014 11:54:52 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:54:53 [ALERT ] LID: inca360_handle_kb: key event: 152575470
25/7/2014 11:54:53 [DEBUG1] LID: handle_next_key_events: key time: 152575470 last time: 152572860
25/7/2014 11:54:53 [DEBUG1] LID: handle_next_key_events: play key
25/7/2014 11:54:53 [DEBUG1] LID: handle_next_key_events: set last key event time: 152575470
25/7/2014 11:54:53 [ALERT ] LID: inca360_handle_kb: key event: 152575720
25/7/2014 11:54:53 [DEBUG1] LID: handle_next_key_events: key time: 152575720 last time: 152575470
25/7/2014 11:54:53 [DEBUG1] LID: handle_next_key_events: play key
25/7/2014 11:54:53 [ERROR ] LID: Unknown DTMF key code: 67
25/7/2014 11:54:53 [DEBUG1] PHN: Goto Best State from 'Minibrowser' (31), force 1
25/7/2014 11:54:53 [DEBUG1] GUI: set_audio_device: early offhook: incoming rings 0
25/7/2014 11:54:53 [DEBUG1] GUI: SetSynthMode 0 -> 0, audio 1, force 1
25/7/2014 11:54:53 [DEBUG1] GUI: Speaker Dest 0
25/7/2014 11:54:53 [DEBUG1] GUI: Speaker Volume 0 ()
25/7/2014 11:54:53 [DEBUG1] GUI: Speaker Mixer 0
25/7/2014 11:54:53 [DEBUG1] GUI: PlaySynth PLAY 0 0 0
25/7/2014 11:54:53 [DEBUG1] LID: handle_next_key_events: set last key event time: 152575720
25/7/2014 11:54:54 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:54:56 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:54:58 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:00 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:02 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:04 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:06 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:08 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:09 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:55:09 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:55:11 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:12 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:15 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:16 [DEBUG1] PHN: SIP: send BYE (1: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:18 [DEBUG1] PHN: SIP: recv 408 BYE (1: B2B.11.8190333) <- udp:185.29.203.27:5060
25/7/2014 11:55:18 [DEBUG1] PHN: SIP: mark for deletion 35/B2B.11.8190333, cbi 1, 1
25/7/2014 11:55:18 [DEBUG0] PHN: SIP: connection -16 terminated
25/7/2014 11:55:18 [INFO ] PHN: RTP: set_audible(0/0 -> 0/0): RP35 changed=0, update_req=0, adr=185.29.203.27:53316
25/7/2014 11:55:18 [INFO ] PHN: rtp_port::release_turn_resources(), bw_check_enabled=0, rtp relay port=0, rtcp relay port=0
25/7/2014 11:55:18 [INFO ] PHN: RTP: set_audible(0/0 -> 0/0): RP35 changed=0, update_req=0, adr=185.29.203.27:53316
25/7/2014 11:55:19 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:23 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:27 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:31 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:35 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:39 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:39 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:55:39 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:55:43 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:47 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:51 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:53 [DEBUG1] PHN: SIP: remove pending packet: 1002378, all 6
25/7/2014 11:55:53 [DEBUG1] PHN: SIP: remove pending packet: 1002379, all 5
25/7/2014 11:55:55 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:55:59 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:03 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:07 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:09 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:56:09 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:56:11 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:15 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:19 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:23 [DEBUG1] DNS: Removing a srv01.cheapconnect.net: 185.29.203.27
25/7/2014 11:56:23 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:27 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:31 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:35 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:39 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:56:39 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:56:39 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:43 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:47 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:51 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:55 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:56:59 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:03 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:06 [DEBUG1] PHN: SIP: remove pending packet: 1002382, all 4
25/7/2014 11:57:06 [DEBUG1] PHN: SIP: remove pending packet: 1002383, all 3
25/7/2014 11:57:07 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:09 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:57:09 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:57:11 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:16 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:20 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:24 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:28 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:32 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:36 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:39 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:57:39 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:57:40 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:44 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:48 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:52 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:57:56 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:00 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:04 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:08 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:09 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:58:09 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:58:12 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:16 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:20 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:24 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:28 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:28 [DEBUG1] PHN: SIP: remove receive packet: 2514, all 2
25/7/2014 11:58:32 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:36 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:39 [DEBUG1] PHN: Sending Keepalive to Watchdog
25/7/2014 11:58:39 [INFO ] LID: Watchdog: keep fd dog = 10
25/7/2014 11:58:40 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:44 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:48 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:52 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:58:56 [DEBUG1] PHN: SIP: send 200 INVITE (343: B2B.11.8190333) -> udp:185.29.203.27:5060
25/7/2014 11:59:00 [ERROR ] PHN: SIP: transaction_timeout udp: 1002386 (256000)
25/7/2014 11:59:00 [NOTICE] PHN: SIP: final transport error: 1002386 -> udp:185.29.203.27:5060
25/7/2014 11:59:00 [ALERT ] PHN: SIP: closing call -16 due to missing ack
25/7/2014 11:59:00 [FATAL ] PHN: SIP: final transport error: invalid id 1002386