[Erledigt] Snom D385 registriert sich an 6850LTE nicht

myfb7590

Neuer User
Mitglied seit
16 Jan 2019
Beiträge
59
Punkte für Reaktionen
3
Punkte
8
Hallo und guten Tag,
vielleicht weiß ja jemand Rat... habe ein D385 am LAN1 einer AVM Fritz!Box 6850 LTE hängen.
Auf der Fritz!Box sind mehrere Rufnummern eingerichtet, Telefonie funktioniert über DECT (Fritz!Fon) und die AVM Fon-App (iOS).
Nur mein Tischtelefon will sich nicht registrieren... habe für das Snom eigene Konten auf der Fritz!Box eingerichtet und die Daten entsprechend als Identitäten hinterlegt (insgesamt sind es drei Konten).

Auszug aus dem Log:
Aug 17 11:15:36.046 [ERROR ] PHN: No reply for naptr lookup
Aug 17 11:15:36.429 [ERROR ] PHN: No reply for srv lookup
Aug 17 11:15:36.859 [ERROR ] PHN: No reply for srv lookup
Aug 17 11:15:37.239 [ERROR ] PHN: No reply for srv lookup
Aug 17 11:16:09.379 [ERROR ] SIP: transport error: 1005694 -> Udp:192.168.188.7:5060
Aug 17 11:16:09.379 [ERROR ] SIP: request 1005694 destination invalid Udp:192.168.188.7:5060 4432d964fd5d-9nb6pozm1utw
Aug 17 11:16:09.379 [ERROR ] SIP: transport error 1005694: generating fake 599
Aug 17 11:16:09.380 [ERROR ] SIP: Registrar [email protected] timed out
Aug 17 11:16:32.123 [ERROR ] SIP: transport error: 1005696 -> Udp:192.168.188.7:5060
Aug 17 11:16:32.123 [ERROR ] SIP: request 1005696 destination invalid Udp:192.168.188.7:5060 4432d964285d-glphqsbscye7
Aug 17 11:16:32.123 [ERROR ] SIP: transport error 1005696: generating fake 599
Aug 17 11:16:32.125 [ERROR ] SIP: Registrar [email protected] timed out
Und etwas ausführlicher:
Aug 17 11:26:57.621 [INFO ] PHN: HandleWatchdog: status: 1 -> 1, timeout: 60, dog_fd: 5, setting set: 0 value:
Aug 17 11:26:57.713 [INFO ] PHN: WEB: Request 39/777/0: POST /log.htm HTTP/1.1
Aug 17 11:26:57.714 [INFO ] PHN: WEB: 39 Digest authentication
Aug 17 11:26:58.839 [DEBUG2] SIP: sip::send_register: reg=2, ttl=3600
Aug 17 11:26:58.839 [DEBUG2] PHN: SipRedirectTargets::popExpired
Aug 17 11:26:58.841 [DEBUG1] SIP: Routing to outbound proxy: sip:fritzfon
Aug 17 11:26:58.841 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=url ? sip:fritzfon all 1
Aug 17 11:26:58.842 [DEBUG2] SIP: Registration Metrics start
Aug 17 11:26:58.843 [DEBUG1] SIP: Routing to outbound proxy: sip:fritzfon
Aug 17 11:26:58.843 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=url ? sip:fritzfon all 2
Aug 17 11:26:58.843 [DEBUG1] PHN: Request 15395 for 'fritzfon.andiling.local' already pending
Aug 17 11:26:58.854 [DEBUG2] PHN: [PROXY] Caching direct answer at \010fritzfon\007andiling\002local\000\000\001
Aug 17 11:26:58.854 [DEBUG2] PHN: [PROXY] Looking in cache for query \010fritzfon\007andiling\002local\000\000\001
Aug 17 11:26:58.854 [DEBUG2] PHN: [PROXY] Fetching \010fritzfon\007andiling\002local\000\000\001 from cache
Aug 17 11:26:58.855 [DEBUG2] PHN: [PROXY] Sending SERVER FAIL for query \005_sips\004_tcp\010fritzfon\000\000\041
Aug 17 11:26:58.855 [DEBUG2] PHN: [PROXY] Sending SERVER FAIL for query \004_sip\004_tcp\010fritzfon\000\000\041
Aug 17 11:26:58.855 [DEBUG2] PHN: [PROXY] Sending SERVER FAIL for query \004_sip\004_udp\010fritzfon\000\000\041
Aug 17 11:26:58.855 [DEBUG2] PHN: [PROXY] Got DNS query for \010fritzfon\007andiling\002local\000\000\043
Aug 17 11:26:58.855 [DEBUG2] PHN: [PROXY] Looking in cache for query \010fritzfon\007andiling\002local\000\000\043
Aug 17 11:26:58.855 [DEBUG2] PHN: [PROXY] Nothing found for \010fritzfon\007andiling\002local\000\000\043
Aug 17 11:27:00.884 [NOTICE] PHN: TPL: Socket 39 idle/connect timeout
Aug 17 11:27:00.884 [DEBUG2] PHN: WEB: HTTP 39/80 disconnected
Aug 17 11:27:00.885 [DEBUG2] PHN: WEB: HTTP 39/80 deleted
Aug 17 11:27:00.930 [DEBUG2] PHN: WEB: HTTP accepting from Tcp:192.168.188.1:53157
Aug 17 11:27:00.931 [DEBUG2] PHN: WEB: HTTP 40/80 accepted from Tcp:192.168.188.1:53157
Aug 17 11:27:00.931 [DEBUG2] PHN: WEB: HTTP 40/80 connected
Aug 17 11:27:00.932 [INFO ] PHN: WEB: Request 40/667/0: GET /line_login.htm?l=1 HTTP/1.1
Aug 17 11:27:00.932 [INFO ] PHN: WEB: 40 Digest authentication
Aug 17 11:27:01.982 [ERROR ] PHN: No reply for naptr lookup
Aug 17 11:27:01.982 [DEBUG1] PHN: Add Cache: fritzfon.andiling.local= (ttl=60, type=3)
Aug 17 11:27:01.982 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=url ? sip:fritzfon all 2
Aug 17 11:27:01.983 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=srv Tls:_sips._tcp.fritzfon all 2
Aug 17 11:27:01.984 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=url ? sip:fritzfon all 2
Aug 17 11:27:01.984 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=srv Tls:_sips._tcp.fritzfon all 2
Aug 17 11:27:01.985 [DEBUG1] PHN: Request 15404 for '_sips._tcp.fritzfon' already pending
Aug 17 11:27:02.350 [ERROR ] PHN: No reply for srv lookup
Aug 17 11:27:02.350 [DEBUG1] PHN: Add Cache: _sips._tcp.fritzfon= (ttl=60, type=1)
Aug 17 11:27:02.350 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=srv Tls:_sips._tcp.fritzfon all 2
Aug 17 11:27:02.350 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=srv Tcp:_sip._tcp.fritzfon all 2
Aug 17 11:27:02.352 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=srv Tls:_sips._tcp.fritzfon all 2
Aug 17 11:27:02.352 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=srv Tcp:_sip._tcp.fritzfon all 2
Aug 17 11:27:02.352 [DEBUG1] PHN: Request 15412 for '_sip._tcp.fritzfon' already pending
Aug 17 11:27:02.561 [INFO ] PHN: WEB: Request 40/3171/0: POST /line_login.htm?l=1 HTTP/1.1
Aug 17 11:27:02.566 [INFO ] PHN: WEB: 40 Digest authentication
Aug 17 11:27:02.574 [CRITIC] PHN: Warning: Ignore invalid parameter: REREGISTER
Aug 17 11:27:02.576 [CRITIC] PHN: Warning: Ignore invalid parameter: save_identity_photo
Aug 17 11:27:02.577 [CRITIC] PHN: Warning: Ignore invalid parameter: REREGISTER:1
Aug 17 11:27:02.577 [DEBUG2] SIP: sip::send_register: reg=0, ttl=3600
Aug 17 11:27:02.577 [DEBUG2] PHN: SipRedirectTargets::popExpired
Aug 17 11:27:02.580 [DEBUG1] SIP: Routing to outbound proxy: sip:fritzfon
Aug 17 11:27:02.580 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=url ? sip:fritzfon all 3
Aug 17 11:27:02.580 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=srv Tls:_sips._tcp.fritzfon all 3
Aug 17 11:27:02.580 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=srv Tcp:_sip._tcp.fritzfon all 3
Aug 17 11:27:02.580 [DEBUG1] PHN: Request 15412 for '_sip._tcp.fritzfon' already pending
Aug 17 11:27:02.581 [DEBUG2] SIP: Registration Metrics start
Aug 17 11:27:02.581 [DEBUG1] SIP: Routing to outbound proxy: sip:fritzfon
Aug 17 11:27:02.581 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=url ? sip:fritzfon all 4
Aug 17 11:27:02.582 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=srv Tls:_sips._tcp.fritzfon all 4
Aug 17 11:27:02.582 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=srv Tcp:_sip._tcp.fritzfon all 4
Aug 17 11:27:02.582 [DEBUG1] PHN: Request 15412 for '_sip._tcp.fritzfon' already pending
Aug 17 11:27:02.582 [CRITIC] PHN: Warning: Ignore invalid parameter: REREGISTER
Aug 17 11:27:02.585 [CRITIC] PHN: Warning: Ignore invalid parameter: save_identity_photo
Aug 17 11:27:02.585 [CRITIC] PHN: Warning: Ignore invalid parameter: REREGISTER:1
Aug 17 11:27:02.739 [ERROR ] PHN: No reply for srv lookup
Aug 17 11:27:02.740 [DEBUG1] PHN: Add Cache: _sip._tcp.fritzfon= (ttl=60, type=1)
Aug 17 11:27:02.740 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=srv Tcp:_sip._tcp.fritzfon all 4
Aug 17 11:27:02.740 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=srv Tcp:_sip._tcp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] PHN: Request 15430 for '_sip._udp.fritzfon' already pending
Aug 17 11:27:02.742 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=srv Tcp:_sip._tcp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] PHN: Request 15430 for '_sip._udp.fritzfon' already pending
Aug 17 11:27:02.742 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=srv Tcp:_sip._tcp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:02.742 [DEBUG1] PHN: Request 15430 for '_sip._udp.fritzfon' already pending
Aug 17 11:27:03.135 [ERROR ] PHN: No reply for srv lookup
Aug 17 11:27:03.135 [DEBUG1] PHN: Add Cache: _sip._udp.fritzfon= (ttl=60, type=1)
Aug 17 11:27:03.135 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:03.135 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.137 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:03.137 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.137 [DEBUG1] PHN: Request 15432 for 'fritzfon.andiling.local' already pending
Aug 17 11:27:03.138 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:03.138 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.138 [DEBUG1] PHN: Request 15432 for 'fritzfon.andiling.local' already pending
Aug 17 11:27:03.138 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=srv Udp:_sip._udp.fritzfon all 4
Aug 17 11:27:03.138 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.138 [DEBUG1] PHN: Request 15432 for 'fritzfon.andiling.local' already pending
Aug 17 11:27:03.145 [DEBUG2] PHN: [PROXY] Sending SERVER FAIL for query \010fritzfon\007andiling\002local\000\000\043
Aug 17 11:27:03.145 [DEBUG2] PHN: [PROXY] Got DNS query for \005_sips\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.145 [DEBUG2] PHN: [PROXY] Looking in cache for query \005_sips\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.145 [DEBUG2] PHN: [PROXY] Nothing found for \005_sips\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.145 [DEBUG2] PHN: [PROXY] Looking in cache for query \005_sips\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Fetching \005_sips\004_tcp\010fritzfon\000\000\041 from cache
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Got DNS query for \004_sip\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Looking in cache for query \004_sip\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Nothing found for \004_sip\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Looking in cache for query \004_sip\004_tcp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Fetching \004_sip\004_tcp\010fritzfon\000\000\041 from cache
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Got DNS query for \004_sip\004_udp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Looking in cache for query \004_sip\004_udp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Nothing found for \004_sip\004_udp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Looking in cache for query \004_sip\004_udp\010fritzfon\000\000\041
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Fetching \004_sip\004_udp\010fritzfon\000\000\041 from cache
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Got DNS query for \010fritzfon\007andiling\002local\000\000\001
Aug 17 11:27:03.146 [DEBUG2] PHN: [PROXY] Looking in cache for query \010fritzfon\007andiling\002local\000\000\001
Aug 17 11:27:03.147 [DEBUG2] PHN: [PROXY] Nothing found for \010fritzfon\007andiling\002local\000\000\001
Aug 17 11:27:03.148 [DEBUG1] PHN: Add Cache: fritzfon.andiling.local=192.168.188.7 (ttl=60, type=5)
Aug 17 11:27:03.148 [DEBUG1] PHN: Add Cache: fritzfon.andiling.local=192.168.188.7 (ttl=60, type=5)
Aug 17 11:27:03.148 [DEBUG1] PHN: Add Cache: fritzfon.andiling.local=192.168.188.7 (ttl=60, type=5)
Aug 17 11:27:03.148 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.149 [DEBUG1] SIP: RoutePendingPacket 1005708: entry=Udp:192.168.188.7:5060 all 4
Aug 17 11:27:03.149 [DEBUG1] SIP: Identity 5ps59wz7 connection id Udp:192.168.188.7:5060
Aug 17 11:27:03.149 [DEBUG0] SIP: Use Connection Udp:192.168.188.7:5060 for packet 1005708
Aug 17 11:27:03.150 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:03.151 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:03.151 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.151 [DEBUG1] SIP: RoutePendingPacket 1005709: entry=Udp:192.168.188.7:5060 all 4
Aug 17 11:27:03.152 [DEBUG2] SIP: Trusted IP Addresses: Udp:192.168.188.7
Aug 17 11:27:03.152 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.152 [DEBUG1] SIP: RoutePendingPacket 1005710: entry=Udp:192.168.188.7:5060 all 4
Aug 17 11:27:03.152 [DEBUG1] SIP: Identity d40q9143 connection id Udp:192.168.188.7:5060
Aug 17 11:27:03.152 [DEBUG0] SIP: Use Connection Udp:192.168.188.7:5060 for packet 1005710
Aug 17 11:27:03.152 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:03.154 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:03.154 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=a Udp:fritzfon all 4
Aug 17 11:27:03.154 [DEBUG1] SIP: RoutePendingPacket 1005711: entry=Udp:192.168.188.7:5060 all 4
Aug 17 11:27:03.154 [DEBUG2] SIP: Trusted IP Addresses: Udp:192.168.188.7
Aug 17 11:27:03.668 [DEBUG2] SIP: resend_packet: id 1005708 in 1000/500 all 4
Aug 17 11:27:03.669 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:03.671 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:03.671 [DEBUG2] SIP: resend_packet: id 1005710 in 1000/500 all 4
Aug 17 11:27:03.671 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:03.672 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:04.669 [DEBUG2] SIP: resend_packet: id 1005708 in 2000/1500 all 4
Aug 17 11:27:04.669 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:04.671 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:04.671 [DEBUG2] SIP: resend_packet: id 1005710 in 2000/1500 all 4
Aug 17 11:27:04.671 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:04.673 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:05.543 [INFO ] PHN: WEB: Request 40/656/0: GET /log.htm HTTP/1.1
Aug 17 11:27:05.544 [INFO ] PHN: WEB: 40 Digest authentication
Aug 17 11:27:06.669 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/3500 all 4
Aug 17 11:27:06.669 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:06.671 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:06.671 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/3500 all 4
Aug 17 11:27:06.671 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:06.673 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:08.639 [NOTICE] PHN: TPL: Socket 40 idle/connect timeout
Aug 17 11:27:08.639 [DEBUG2] PHN: WEB: HTTP 40/80 disconnected
Aug 17 11:27:08.639 [DEBUG2] PHN: WEB: HTTP 40/80 deleted
Aug 17 11:27:10.669 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/7500 all 4
Aug 17 11:27:10.670 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:10.671 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:10.671 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/7500 all 4
Aug 17 11:27:10.671 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:10.673 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:14.679 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/11500 all 4
Aug 17 11:27:14.679 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:14.681 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:14.681 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/11500 all 4
Aug 17 11:27:14.681 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:14.682 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:16.791 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Aug 17 11:27:16.791 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 1), from_hardware 1, is_longpressed 0, State Idle (26), time 315911042
Aug 17 11:27:16.792 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Aug 17 11:27:16.792 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 0), from_hardware 1, is_longpressed 0, State Idle (26), time 315911106
Aug 17 11:27:16.800 [DEBUG0] UXM: Request backlight for module -1 with value 15
Aug 17 11:27:18.679 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/15500 all 4
Aug 17 11:27:18.679 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:18.681 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:18.681 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/15500 all 4
Aug 17 11:27:18.681 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:18.683 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:22.703 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/19500 all 4
Aug 17 11:27:22.703 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:22.705 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:22.705 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/19500 all 4
Aug 17 11:27:22.705 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:22.707 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:24.489 [INFO ] PHN: LLDP: Packet processing disabled, skipping packet
Aug 17 11:27:26.709 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/23500 all 4
Aug 17 11:27:26.709 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:26.710 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:26.710 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/23500 all 4
Aug 17 11:27:26.710 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:26.712 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:27.629 [DEBUG1] PHN: Sending Keepalive to Watchdog
Aug 17 11:27:27.631 [INFO ] PHN: HandleWatchdog: status: 1 -> 1, timeout: 60, dog_fd: 5, setting set: 0 value:
Aug 17 11:27:30.753 [DEBUG2] SIP: resend_packet: id 1005708 in 4000/27500 all 4
Aug 17 11:27:30.753 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:30.755 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:30.756 [DEBUG2] SIP: resend_packet: id 1005710 in 4000/27500 all 4
Aug 17 11:27:30.756 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:30.757 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:30.849 [INFO ] SIP: Identity 3 reregister in 284000 ms.
Aug 17 11:27:34.590 [INFO ] SIP: Identity 1 reregister in 313000 ms.
Aug 17 11:27:34.759 [DEBUG2] SIP: resend_packet: id 1005708 in 500/31500 all 4
Aug 17 11:27:34.759 [DEBUG0] SIP: send REGISTER (950: 4432d964fd5d-9nb6pozm1utw) -> Udp:192.168.188.7:5060
Aug 17 11:27:34.760 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:34.760 [DEBUG2] SIP: resend_packet: id 1005710 in 500/31500 all 4
Aug 17 11:27:34.761 [DEBUG0] SIP: send REGISTER (950: 4432d964925b-thskrqvnzgby) -> Udp:192.168.188.7:5060
Aug 17 11:27:34.762 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:35.353 [WARN ] SIP: transaction_timeout udp: 1005708 (32000ms)->Udp:192.168.188.7:5060
Aug 17 11:27:35.354 [ERROR ] SIP: transport error: 1005708 -> Udp:192.168.188.7:5060
Aug 17 11:27:35.354 [NOTICE] SIP: Add dirty host: Udp:192.168.188.7:5060 (0 sec)
Aug 17 11:27:35.354 [DEBUG2] SIP: setup dirty host expiration for (2820/0) Udp:192.168.188.7:5060 in 0 secs
Aug 17 11:27:35.354 [ERROR ] SIP: request 1005708 destination invalid Udp:192.168.188.7:5060 4432d964fd5d-9nb6pozm1utw
Aug 17 11:27:35.354 [NOTICE] SIP: final transport error: 1005708 -> Udp:192.168.188.7:5060
Aug 17 11:27:35.355 [ERROR ] SIP: transport error 1005708: generating fake 599
Aug 17 11:27:35.355 [DEBUG1] SIP: remove pending packet: 1005708, all 4
Aug 17 11:27:35.355 [ERROR ] SIP: request 1005710 destination invalid Udp:192.168.188.7:5060 4432d964925b-thskrqvnzgby
Aug 17 11:27:35.355 [NOTICE] SIP: final transport error: 1005710 -> Udp:192.168.188.7:5060
Aug 17 11:27:35.355 [ERROR ] SIP: transport error 1005710: generating fake 599
Aug 17 11:27:35.355 [DEBUG1] SIP: remove pending packet: 1005710, all 3
Aug 17 11:27:35.356 [DEBUG1] SIP: recv 599 REGISTER (950: 4432d964fd5d-9nb6pozm1utw) <- ?:
Aug 17 11:27:35.356 [DEBUG0] SIP: sip::process_registrar_packet: Received 599 Network Failure
Aug 17 11:27:35.356 [ERROR ] SIP: Registrar [email protected] timed out
Aug 17 11:27:35.356 [INFO ] SIP: stop subscription 2/ec66db64c252-tob56349k1ok
Aug 17 11:27:35.356 [INFO ] SIP: stop subscription 2/ec66db644353-9ksj4dr84b2v
Aug 17 11:27:35.356 [INFO ] SIP: stop subscription 2/ec66db64a353-qspjw15rzb7v
Aug 17 11:27:35.356 [DEBUG2] PHN: SipRedirectTargets::popExpired
Aug 17 11:27:35.356 [DEBUG2] PHN: SipRedirectTargets::Clear
Aug 17 11:27:35.357 [INFO ] SIP: Identity 3 reregister in 313000 ms.
Aug 17 11:27:35.357 [INFO ] SIP: Identity 3 reregister in 294000 ms.
Aug 17 11:27:35.357 [DEBUG2] SIP: Registration Metrics: 599, , 300, 300
Aug 17 11:27:35.357 [DEBUG2] SIP: registration report 599/181: 'x-RegData: RPN=fritz.box RCN=sip:[email protected]:55802;line=5ps59wz7
x-RegMetrics: RRD=36514 RRS=599
x-UserAgent:snomD375/10.1.64.17
CallID:4432d964fd5d-9nb6pozm1utw
'
Aug 17 11:27:35.357 [DEBUG1] SIP: recv 599 REGISTER (950: 4432d964925b-thskrqvnzgby) <- ?:
Aug 17 11:27:35.357 [DEBUG0] SIP: sip::process_registrar_packet: Received 599 Network Failure
Aug 17 11:27:35.358 [ERROR ] SIP: Registrar [email protected] timed out
Aug 17 11:27:35.358 [DEBUG2] PHN: SipRedirectTargets::popExpired
Aug 17 11:27:35.358 [DEBUG2] PHN: SipRedirectTargets::Clear
Aug 17 11:27:35.358 [INFO ] SIP: Identity 1 reregister in 288000 ms.
Aug 17 11:27:35.358 [INFO ] SIP: Identity 1 reregister in 282000 ms.
Aug 17 11:27:35.358 [DEBUG2] SIP: Registration Metrics: 599, , 300, 300
Aug 17 11:27:35.358 [DEBUG2] SIP: registration report 599/182: 'x-RegData: RPN=fritz.box RCN=sip:[email protected]:55802;line=d40q9143
x-RegMetrics: RRD=32777 RRS=599
x-UserAgent:snomD375/10.1.64.17
CallID:4432d964925b-thskrqvnzgby
'
Aug 17 11:27:36.809 [DEBUG0] UXM: Request backlight for module -1 with value 1
Aug 17 11:27:37.719 [DEBUG2] SIP: sip::send_register: reg=1, ttl=3600
Aug 17 11:27:37.719 [DEBUG2] PHN: SipRedirectTargets::popExpired
Aug 17 11:27:37.720 [DEBUG1] SIP: Routing to outbound proxy: sip:fritzfon
Aug 17 11:27:37.721 [DEBUG1] SIP: RoutePendingPacket 1005712: entry=url ? sip:fritzfon all 3
Aug 17 11:27:37.721 [DEBUG1] SIP: RoutePendingPacket 1005712: entry=srv Tls:_sips._tcp.fritzfon all 3
Aug 17 11:27:37.721 [DEBUG1] SIP: RoutePendingPacket 1005712: entry=srv Tcp:_sip._tcp.fritzfon all 3
Aug 17 11:27:37.721 [DEBUG1] SIP: RoutePendingPacket 1005712: entry=srv Udp:_sip._udp.fritzfon all 3
Aug 17 11:27:37.721 [DEBUG1] SIP: RoutePendingPacket 1005712: entry=a Udp:fritzfon all 3
Aug 17 11:27:37.722 [DEBUG1] SIP: RoutePendingPacket 1005712: entry=Udp:192.168.188.7:5060 all 3
Aug 17 11:27:37.722 [DEBUG1] SIP: Identity azpxxhd9 connection id Udp:192.168.188.7:5060
Aug 17 11:27:37.722 [DEBUG0] SIP: Use Connection Udp:192.168.188.7:5060 for packet 1005712
Aug 17 11:27:37.722 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:37.724 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:37.724 [DEBUG2] SIP: Registration Metrics start
Aug 17 11:27:37.725 [DEBUG1] SIP: Routing to explicit plan Udp:192.168.188.7:5060
Aug 17 11:27:37.725 [DEBUG1] SIP: RoutePendingPacket 1005713: entry=Udp:192.168.188.7:5060 all 4
Aug 17 11:27:37.725 [DEBUG2] SIP: Trusted IP Addresses: Udp:192.168.188.7
Aug 17 11:27:38.229 [DEBUG2] SIP: resend_packet: id 1005712 in 1000/500 all 4
Aug 17 11:27:38.229 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:38.230 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:39.229 [DEBUG2] SIP: resend_packet: id 1005712 in 2000/1500 all 4
Aug 17 11:27:39.229 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:39.230 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:41.229 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/3500 all 4
Aug 17 11:27:41.229 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:41.231 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:45.229 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/7500 all 4
Aug 17 11:27:45.229 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:45.231 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:49.229 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/11500 all 4
Aug 17 11:27:49.229 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:49.231 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:53.229 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/15500 all 4
Aug 17 11:27:53.230 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:53.231 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:54.488 [INFO ] PHN: LLDP: Packet processing disabled, skipping packet
Aug 17 11:27:57.239 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/19500 all 4
Aug 17 11:27:57.239 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:27:57.240 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:27:57.639 [DEBUG1] PHN: Sending Keepalive to Watchdog
Aug 17 11:27:57.641 [INFO ] PHN: HandleWatchdog: status: 1 -> 1, timeout: 60, dog_fd: 5, setting set: 0 value:
Aug 17 11:28:01.239 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/23500 all 4
Aug 17 11:28:01.239 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:28:01.240 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:28:01.988 [DEBUG1] PHN: Removing naptr fritzfon.andiling.local:
Aug 17 11:28:02.358 [DEBUG1] PHN: Removing srv _sips._tcp.fritzfon:
Aug 17 11:28:02.579 [DEBUG2] GUI: If_Registration(REFUSED, 0, [email protected],Network Failure)
Aug 17 11:28:02.748 [DEBUG1] PHN: Removing srv _sip._tcp.fritzfon:
Aug 17 11:28:03.139 [DEBUG1] PHN: Removing srv _sip._udp.fritzfon:
Aug 17 11:28:03.149 [DEBUG1] PHN: Removing addrinfo fritzfon.andiling.local: 192.168.188.7
Aug 17 11:28:05.239 [DEBUG2] SIP: resend_packet: id 1005712 in 4000/27500 all 4
Aug 17 11:28:05.239 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:28:05.241 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:28:07.358 [DEBUG1] SIP: remove receive packet: 2873, all 2
Aug 17 11:28:07.359 [DEBUG1] SIP: remove receive packet: 2874, all 1
Aug 17 11:28:08.849 [DEBUG1] SIP: remove pending packet: 1005709, all 4
Aug 17 11:28:09.341 [DEBUG2] SIP: resend_packet: id 1005712 in 500/31500 all 3
Aug 17 11:28:09.341 [DEBUG0] SIP: send REGISTER (951: 4432d964285d-glphqsbscye7) -> Udp:192.168.188.7:5060
Aug 17 11:28:09.343 [DEBUG2] SIP: Updated Transport 0x1728528 TTL 360000
Aug 17 11:28:09.729 [INFO ] SIP: Identity 2 reregister in 301000 ms.
Aug 17 11:28:09.849 [WARN ] SIP: transaction_timeout udp: 1005712 (32000ms)->Udp:192.168.188.7:5060
Aug 17 11:28:09.849 [ERROR ] SIP: transport error: 1005712 -> Udp:192.168.188.7:5060
Aug 17 11:28:09.849 [NOTICE] SIP: Add dirty host: Udp:192.168.188.7:5060 (0 sec)
Aug 17 11:28:09.849 [DEBUG2] SIP: setup dirty host expiration for (2821/0) Udp:192.168.188.7:5060 in 0 secs
Aug 17 11:28:09.849 [ERROR ] SIP: request 1005712 destination invalid Udp:192.168.188.7:5060 4432d964285d-glphqsbscye7
Aug 17 11:28:09.849 [NOTICE] SIP: final transport error: 1005712 -> Udp:192.168.188.7:5060
Aug 17 11:28:09.849 [ERROR ] SIP: transport error 1005712: generating fake 599
Aug 17 11:28:09.849 [DEBUG1] SIP: remove pending packet: 1005712, all 3
Aug 17 11:28:09.850 [DEBUG1] SIP: recv 599 REGISTER (951: 4432d964285d-glphqsbscye7) <- ?:
Aug 17 11:28:09.850 [DEBUG0] SIP: sip::process_registrar_packet: Received 599 Network Failure
Aug 17 11:28:09.850 [ERROR ] SIP: Registrar [email protected] timed out
Aug 17 11:28:09.850 [INFO ] SIP: stop subscription 1/bfbfdd64332d-sc9lgt6a7llv
Aug 17 11:28:09.850 [INFO ] SIP: stop subscription 1/bfbfdd649a2d-1t6vlr0gor74
Aug 17 11:28:09.850 [INFO ] SIP: stop subscription 1/bfbfdd64fc2d-m462cxa0m7vj
Aug 17 11:28:09.850 [DEBUG2] PHN: SipRedirectTargets::popExpired
Aug 17 11:28:09.850 [DEBUG2] PHN: SipRedirectTargets::Clear
Aug 17 11:28:09.850 [INFO ] SIP: Identity 2 reregister in 272000 ms.
Aug 17 11:28:09.851 [INFO ] SIP: Identity 2 reregister in 287000 ms.
Aug 17 11:28:09.851 [DEBUG2] SIP: Registration Metrics: 599, , 300, 300
Aug 17 11:28:09.851 [DEBUG2] SIP: registration report 599/182: 'x-RegData: RPN=fritz.box RCN=sip:[email protected]:55802;line=azpxxhd9
x-RegMetrics: RRD=32126 RRS=599
x-UserAgent:snomD375/10.1.64.17
CallID:4432d964285d-glphqsbscye7
'
Aug 17 11:28:12.684 [DEBUG1] SIP: remove pending packet: 1005711, all 2
Aug 17 11:28:24.501 [INFO ] PHN: LLDP: Packet processing disabled, skipping packet
Aug 17 11:28:25.460 [DEBUG1] PHN: LID::key_pressed: origin key event, state 1
Aug 17 11:28:25.460 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 1), from_hardware 1, is_longpressed 0, State Idle (26), time 315979797
Aug 17 11:28:25.480 [DEBUG1] PHN: LID::key_pressed: origin key event, state 2
Aug 17 11:28:25.480 [DEBUG2] PHN: gui_keys: on key event (CANCEL, 0), from_hardware 1, is_longpressed 0, State Idle (26), time 315979829
Aug 17 11:28:25.488 [DEBUG0] UXM: Request backlight for module -1 with value 15
Aug 17 11:28:27.675 [DEBUG1] PHN: Sending Keepalive to Watchdog
Aug 17 11:28:27.681 [INFO ] PHN: HandleWatchdog: status: 1 -> 1, timeout: 60, dog_fd: 5, setting set: 0 value:
Aug 17 11:28:31.980 [DEBUG2] PHN: WEB: HTTP accepting from Tcp:192.168.188.1:53160
Aug 17 11:28:31.981 [DEBUG2] PHN: WEB: HTTP 41/80 accepted from Tcp:192.168.188.1:53160
Aug 17 11:28:31.981 [DEBUG2] PHN: WEB: HTTP 41/80 connected
Aug 17 11:28:31.982 [INFO ] PHN: WEB: Request 41/762/0: POST /log.htm HTTP/1.1
Aug 17 11:28:31.983 [INFO ] PHN: WEB: 41 Digest authentication
Die IP 192.168.188.7 ist die der Fritz!Box, 192.168.188.19 vom snom.
Es scheint ein timeout zu sein aber ich wieß nicht warum, das Telefon funktioniert soweit, ist vom Heimnetz erreichbar und kann auch nach draußen funken (z.B. Updates).

Vielen Dank und viele Grüsse,
Andreas
 
Zuletzt bearbeitet:
wenn AVM password Vorgabe eingehalten (Groß/Kleinschreibung/Zahlen usw.), router reboot.
 
Nach dem Neustart des Routers registriert sich das Telefon für etwa drei Minuten erfolgreich, dann verliert es die Registrierung wieder.
 
akt. fw. auf dem phone ? Evtl. full reset und erneut config. manuell edit.
 
Gemacht, dann nur Sprache, Zeitzone und die erste Identität eingetragen, Ergebnis ist das Gleiche. ):
 
schon einen alternativen sip registrar / provider mit diesem phone getestet ?
 
Vorher war das Telefon an einer Fritz!Box 7590 und da tat es anstandslos seine Dienste.
Ich habe eben nochmals einen Reset gemacht und die Identitäten neu eingetragen, aktuell funktioniert es.
Einziger Unterschied zu vorher, ich habe auch beim Proxy noch wie beim Registrar fritz.box eingetragen, sollte aber nicht notwendig sein.

Ergänzung:
Auch drei Tage später steht die Verbindung noch, scheint also jetzt zu funktionieren.
Danke, @DukeFake.
 
Zuletzt bearbeitet:
Holen Sie sich 3CX - völlig kostenlos!
Verbinden Sie Ihr Team und Ihre Kunden Telefonie Livechat Videokonferenzen

Gehostet oder selbst-verwaltet. Für bis zu 10 Nutzer dauerhaft kostenlos. Keine Kreditkartendetails erforderlich. Ohne Risiko testen.

3CX
Für diese E-Mail-Adresse besteht bereits ein 3CX-Konto. Sie werden zum Kundenportal weitergeleitet, wo Sie sich anmelden oder Ihr Passwort zurücksetzen können, falls Sie dieses vergessen haben.