Paul Multi

Please elaborate?

Hi, I have some strange errors from one client in my 3cx network and I cant pin point the root cause. For a moment I thought it could be the PnP settings (url) how ever after an update the issue perssits. 1/1/1970 00:00:00 [ALERT ] PHN: Starting up 2 1/1/1970 00:00:00 [ALERT ] PHN: Starting up 3 1/1/2000 00:00:32 [ALERT ] CFG: read_old_settings: No /snomconfig/raw_settings found for reading. 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found phone-settings XML header 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found one byte encoding: 0 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found network-settings XML header 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found one byte encoding: 1 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found ReplacementPlan XML header 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found one byte encoding: 1 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found function-keys XML header 1/1/2000 00:00:32 [NOTICE] CFG: read_xml_settings: found one byte encoding: 0 1/1/2000 00:00:33 [NOTICE] CFG: read_xml_settings: found firmware-settings XML header 1/1/2000 00:00:33 [NOTICE] CFG: read_xml_settings: found one byte encoding: 0 1/1/2000 00:00:33 [CRITIC] GUI: Display disabled state: [off] 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_DA.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_DE.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_EN.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_ES.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_FR.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_IT.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_NL.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_PT.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_RU.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Fast read of /mnt/snomlang/gui_lang_SV.xml successful. 1/1/2000 00:00:33 [NOTICE] PHN: Using gui lang Svenska at index:9 from: /mnt/snomlang/gui_lang_SV.xml 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_DA.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_DE.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_EN.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_ES.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_FR.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_IT.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_NL.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_PT.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Fast read of /mnt/snomlang/web_lang_RU.xml successful. 1/1/2000 00:00:34 [NOTICE] PHN: Using web lang English at index:2 from: /mnt/snomlang/web_lang_EN.xml 1/1/2000 00:00:35 [NOTICE] CFG: read_xml_settings: found dial-plan XML header 1/1/2000 00:00:35 [NOTICE] CFG: read_xml_settings: found one byte encoding: 0 1/1/2000 00:00:36 [NOTICE] PHN: SIP: Udp listener connected 1/1/2000 00:00:36 [NOTICE] PHN: Setting server prio 1, type redirection, url: >http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126.xml<'>http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126.xml< 1/1/2000 00:00:36 [NOTICE] PHN: Fetching URL: http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126.xml 1/1/2000 00:00:38 [NOTICE] LID: Opening TCP socket on port 7547 1/1/2000 00:00:38 [NOTICE] LID: Opening TCP socket on port 843 1/1/2000 00:00:38 [FATAL ] PHN: Phone::uboot_version:1.1.3-u 1/1/2000 00:00:38 [NOTICE] CFG: read_xml_settings: found no XML header; probably old style format 1/1/2000 00:00:38 [NOTICE] PHN: Config setup: found old style settings 1/1/2000 00:00:38 [NOTICE] PHN: last prov successful:1; uri: >http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126.xml<'>http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126.xml<; default uri: >http://provisioning.snom.com/snom370/snom370.php?mac={mac}< 1/1/2000 00:00:38 [NOTICE] PHN: Fetching URL: http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126-0004133A516E.xml 1/1/2000 00:00:38 [ALERT ] PHN: Config setup: code: 404, uri: http://10.10.5.34:5000/provisioning/TcxProvFiles/3cxProv_130104143719_126-0004133A516E.xml 1/1/2000 00:00:38 [NOTICE] PHN: Fetching URL: http://127.0.0.1:80/dummy.htm 1/1/2000 00:00:38 [ERROR ] PHN: TPL: Socket Error: 11/21/connecting, connect_cb -> Operation now in progress (150) 1/1/2000 00:00:38 [NOTICE] PHN: webclient::on_tcp_close conn_id:3 1/1/2000 00:00:38 [ALERT ] PHN: Config setup: code: 500, uri: http://127.0.0.1:80/dummy.htm 1/1/2000 00:00:38 [NOTICE] PHN: Fetching FW URL: http://10.10.5.34:5000/provisioning/TcxProvFiles/snom370-firmware.htm 1/1/2000 00:00:38 [NOTICE] PHN: Fetching URL: http://10.10.5.34:5000/provisioning/TcxProvFiles/snom370-firmware.htm 1/1/2000 00:00:38 [NOTICE] PHN: Last provisioning was successful, thus stop here! 1/1/2000 00:00:38 [NOTICE] PHN: Go to wizzard if all settings have been read. 1/1/2000 00:00:38 [NOTICE] PHN: SetProvisioningDone 1/1/2000 01:00:39 [CRITIC] PHN: start_dst(954036000) end_dst(972788400) offset_dst(3600) offset_utc(3600) 1/1/2000 01:00:39 [CRITIC] PHN: start DST: 03/26/2000 02:00:00 (954036000) 1/1/2000 01:00:39 [CRITIC] PHN: end DST: 10/29/2000 03:00:00 (972788400) 1/1/2000 01:00:39 [NOTICE] CFG: read_xml_settings: found phone-book XML header 1/1/2000 01:00:39 [NOTICE] CFG: read_xml_settings: found one byte encoding: 0 1/1/2000 01:00:39 [NOTICE] PHN: Starting straiten of tbook, size now is 0 1/1/2000 01:00:39 [NOTICE] PHN: Done straiten of tbook, size now is 0 1/1/2000 01:00:39 [NOTICE] PHN: ProvUtils::UcLookUpFirmwareUpdateViaDNS: domain: >< 1/1/2000 01:00:39 [NOTICE] CFG: Settings: Config stored 1/1/2000 01:00:40 [CRITIC] PHN: SIP: packet 1000001 dest invalid udp:10.10.5.34:5060 for Request DISCOVER/- 1/1/2000 01:00:40 [WARN ] PHN: SIP: process_registrar_packet: 401 needs 128 bit nonce 1/1/2000 01:00:40 [NOTICE] PHN: SIP: process auth:Match challenge for user=143, realm=3CXPhoneSystem 1/1/2000 01:00:41 [CRITIC] PHN: SIP: Registered at registrar as 143@3cx.x.se (Expires: 1800 secs) 14/3/2013 10:19:15 [CRITIC] PHN: start_dst(1364695200) end_dst(1382842800) offset_dst(3600) offset_utc(3600) 14/3/2013 10:19:15 [CRITIC] PHN: start DST: 03/31/2013 02:00:00 (1364695200) 14/3/2013 10:19:15 [CRITIC] PHN: end DST: 10/27/2013 03:00:00 (1382842800) 14/3/2013 10:19:15 [WARN ] PHN: SIP: process_registrar_packet: 401 needs 128 bit nonce 14/3/2013 10:19:15 [NOTICE] PHN: SIP: process auth:Match challenge for user=143, realm=3CXPhoneSystem 14/3/2013 10:19:15 [WARN ] PHN: SIP: process_registrar_packet: 401 needs 128 bit nonce 14/3/2013 10:19:15 [NOTICE] PHN: SIP: process auth:Match challenge for user=143, realm=3CXPhoneSystem 14/3/2013 10:19:18 [NOTICE] PHN: TPL: Socket 20 idle/connect timeout 14/3/2013 10:19:22 [NOTICE] PHN: TPL: Socket 13 idle/connect timeout 14/3/2013 10:19:23 [NOTICE] PHN: TPL: Socket 14 idle/connect timeout 14/3/2013 10:19:31 [ERROR ] PHN: TPL: Socket Error: 21/21/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:31 [ERROR ] PHN: TPL: Socket Error: 22/24/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:31 [ERROR ] PHN: TPL: Socket Error: 23/25/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:31 [ERROR ] PHN: TPL: Socket Error: 24/26/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:31 [ERROR ] PHN: TPL: Socket Error: 25/27/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:34 [NOTICE] PHN: TPL: Socket 27 idle/connect timeout 14/3/2013 10:19:34 [NOTICE] PHN: TPL: Socket 28 idle/connect timeout 14/3/2013 10:19:34 [NOTICE] PHN: TPL: Socket 29 idle/connect timeout 14/3/2013 10:19:34 [NOTICE] PHN: TPL: Socket 30 idle/connect timeout 14/3/2013 10:19:34 [NOTICE] PHN: TPL: Socket 26 idle/connect timeout This part---------------------------------------------------------------------------------------------------------------- 14/3/2013 10:19:37 [ERROR ] PHN: TPL: Socket Error: 32/24/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:37 [ERROR ] PHN: TPL: Socket Error: 33/25/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:37 [ERROR ] PHN: TPL: Socket Error: 34/26/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:37 [ERROR ] PHN: TPL: Socket Error: 35/27/connected, poll data_cb -> Resource temporarily unavailable (11) 14/3/2013 10:19:37 [ERROR ] PHN: TPL: Socket Error: 36/28/connected, read -> Resource temporarily unavailable (11) Any help here would be great