Jump to content

14/3/2013 10:19:31 [ERROR ] PHN: TPL: Socket Error: 25/27/connected, poll data_cb -> Resource temporarily unavailable (11)


Paul Multi
 Share

Recommended Posts

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 :)

Link to comment
Share on other sites

Join the conversation

You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.

Guest
Reply to this topic...

×   Pasted as rich text.   Paste as plain text instead

  Only 75 emoji are allowed.

×   Your link has been automatically embedded.   Display as a link instead

×   Your previous content has been restored.   Clear editor

×   You cannot paste images directly. Upload or insert images from URL.

 Share

×
×
  • Create New...