=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2014.04.24 16:17:32 =~=~=~=~=~=~=~=~=~=~=~= Smart Init is enabled smart init is sizing iomem TYPE MEMORY_REQ Onboard devices & buffer pools 0x0228F000 ----------------------------------------------- TOTAL: 0x0228F000 Rounded IOMEM up to: 36MB. Using 3 percent iomem. [36MB/1024MB] Restricted Rights Legend Use, duplication, or disclosure by the Government is subject to restrictions as set forth in subparagraph (c) of the Commercial Computer Software - Restricted Rights clause at FAR sec. 52.227-19 and subparagraph (c) (1) (ii) of the Rights in Technical Data and Computer Software clause at DFARS sec. 252.227-7013. cisco Systems, Inc. 170 West Tasman Drive San Jose, California 95134-1706 Cisco IOS Software, C2900 Software (C2900-UNIVERSALK9-M), Version 15.4(2)T, RELEASE SOFTWARE (fc1) Technical Support: http://www.cisco.com/techsupport Copyright (c) 1986-2014 by Cisco Systems, Inc. Compiled Wed 26-Mar-14 14:14 by prod_rel_team This product contains cryptographic features and is subject to United States and local country laws governing import, export, transfer and use. Delivery of Cisco cryptographic products does not imply third-party authority to import, export, distribute or use encryption. Importers, exporters, distributors and users are responsible for compliance with U.S. and local country laws. By using this product you agree to comply with applicable laws and regulations. If you are unable to comply with U.S. and local laws, return this product immediately. A summary of U.S. laws governing Cisco cryptographic products may be found at: http://www.cisco.com/wwl/export/crypto/tool/stqrg.html If you require further assistance please contact us by sending email to export@cisco.com. Installed image archive Cisco CISCO2911/K9 (revision 1.0) with 1011712K/36864K bytes of memory. Processor board ID FGL153211GM 3 Gigabit Ethernet interfaces 1 terminal line 1 Virtual Private Network (VPN) Module DRAM configuration is 64 bits wide with parity enabled. 255K bytes of non-volatile configuration memory. 250880K bytes of ATA System CompactFlash 0 (Read/Write) license boot c2900 technology-package securityk9 ^ % Invalid input detected at '^' marker. license boot c2900 technology-package uck9 ^ % Invalid input detected at '^' marker. license boot c2900 technology-package datak9 ^ % Invalid input detected at '^' marker. Reg-Index = 1  Press RETURN to get started! *Jan 2 00:00:03.623: %LICENSE-6-EULA_ACCEPT_ALL: The Right to Use End User License Agreement is accepted *Jan 2 00:00:03.739: %IOS_LICENSE_IMAGE_APPLICATION-6-LICENSE_LEVEL: Module name = c2900 Next reboot level = ipbasek9 and License = ipbasek9 *Jan 2 00:00:03.963: %IOS_LICENSE_IMAGE_APPLICATION-6-LICENSE_LEVEL: Module name = c2900 Next reboot level = securityk9 and License = securityk9 *Jan 2 00:00:04.179: %IOS_LICENSE_IMAGE_APPLICATION-6-LICENSE_LEVEL: Module name = c2900 Next reboot level = uc POC-CUBE>k9 and License = uck9 *Jan 2 00:00:04.399: %IOS_LICENSE_IMAGE_APPLICATION-6-LICENSE_LEVEL: Module name = c2900 Next reboot level = datak9 and License = datak9 *Apr 24 12:40:51.631: c3600_scp_set_dstaddr2_idb(184)add = 80 name is Embedded-Service-Engine0/0 *Apr 24 12:40:58.063: %PVDM-6-UNSUPPORTED: Codec G.723 is not supported on PVDM3. *Apr 24 12:40:59.359: %CTS-6-ENV_DATA_START_STATE: Environment Data Download in start state *Apr 24 12:41:03.731: VOIPHA: removing default handlers *Apr 24 12:41:03.755: %VPN_HW-6-INFO_LOC: Crypto engine: onboard 0 State changed to: Initialized *Apr 24 12:41:03.759: %VPN_HW-6-INFO_LOC: Crypto engine: onboard 0 State changed to: Enabled *Apr 24 12:41:10.603: %LINEPROTO-5-UPDOWN: Line protocol on Interface VoIP-Null0, changed state to up *Apr 24 12:41:10.603: %LINK-3-UPDOWN: Interface GigabitEthernet0/0, changed state to up *Apr 24 12:41:10.603: %LINK-3-UPDOWN: Interface GigabitEthernet0/1, changed state to down *Apr 24 12:41:10.603: %LINK-3-UPDOWN: Interface GigabitEthernet0/2, changed state to down *Apr 24 12:41:11.699: %LINEPROTO-5-UPDOWN: Line protocol on Interface GigabitEthernet0/0, changed state to up *Apr 24 12:41:11.699: %LINEPROTO-5-UPDOWN: Line protocol on Interface GigabitEthernet0/1, changed state to down *Apr 24 12:41:11.699: %LINEPROTO-5-UPDOWN: Line protocol on Interface GigabitEthernet0/2, changed state to down *Apr 24 12:41:13.671: sbc ctl-file ct1 *Apr 24 12:41:13.675: cfg ctl-file ct1, record-entry selfsigned trustpoint self-trustpoint *Apr 24 12:41:13.675: cfg ctl-file ct1, record-entry capf trustpoint capf-trustpoint *Apr 24 12:41:13.675: cfg ctl-file ct1, record-entry cucm-tftp trustpoint cucm_trustpoint *Apr 24 12:41:13.675: cfg ctl-file ct1, complete *Apr 24 12:41:13.675: cfg sbc phone-proxy pp *Apr 24 12:41:13.675: cfg sbc phone-proxy pp, configure tftp-server *Apr 24 12:41:13.679: cfg sbc phone-proxy pp, ctl-file ct1 *Apr 24 12:41:13.679: cfg sbc phone-proxy pp, access-secure *Apr 24 12:41:13.679: cfg sbc phone-proxy pp, complete *Apr 24 12:41:13.679: cfg sbc phone-proxy cubepp *Apr 24 12:41:13.679: cfg sbc phone-proxy cubepp, configure tftp-server *Apr 24 12:41:13.679: cfg sbc phone-proxy cubepp, ctl-file ct1 *Apr 24 12:41:13.683: cfg sbc phone-proxy cubepp, configure service map *Apr 24 12:41:13.683: cfg sbc phone-proxy cubepp, configure service map *Apr 24 12:41:13.683: cfg sbc phone-proxy cubepp, configure service map *Apr 24 12:41:13.683: cfg sbc phone-proxy cubepp, complete *Apr 24 12:41:13.683: sbc phone-proxy tftp-address *Apr 24 12:41:13.683: port-range 40000 50000 *Apr 24 12:41:13.687: sbc phone-proxy file-buffer *Apr 24 12:41:13.707: Create voice ha process now...... *Apr 24 12:41:13.911: %SYS-5-CONFIG_I: Configured from memory by console *Apr 24 12:41:15.583: %LINK-5-CHANGED: Interface Embedded-Service-Engine0/0, changed state to administratively down *Apr 24 12:41:15.583: %LINK-5-CHANGED: Interface GigabitEthernet0/1, changed state to administratively down *Apr 24 12:41:15.583: %LINK-5-CHANGED: Interface GigabitEthernet0/2, changed state to administratively down *Apr 24 12:41:16.527: %SYS-5-RESTART: System restarted -- Cisco IOS Software, C2900 Software (C2900-UNIVERSALK9-M), Version 15.4(2)T, RELEASE SOFTWARE (fc1) Technical Support: http://www.cisco.com/techsupport Copyright (c) 1986-2014 by Cisco Systems, Inc. Compiled Wed 26-Mar-14 14:14 by prod_rel_team *Apr 24 12:41:16.575: %SSH-5-ENABLED: SSH 1.99 has been enabled *Apr 24 12:41:16.583: %LINEPROTO-5-UPDOWN: Line protocol on Interface Embedded-Service-Engine0/0, changed state to down *Apr 24 12:41:17.207: %SYS-6-BOOTTIME: Time taken to reboot after reload = 136 seconds *Apr 24 12:41:17.871: %CRYPTO-6-ISAKMP_ON_OFF: ISAKMP is OFF *Apr 24 12:41:17.871: %CRYPTO-6-GDOI_ON_OFF: GDOI is OFF *Apr 24 12:41:17.871: %CRYPTO-6-ISAKMP_ON_OFF: ISAKMP is OFF *Apr 24 12:41:17.871: %CRYPTO-6-GDOI_ON_OFF: GDOI is OFF *Apr 24 12:41:23.547: unstructuredName:POC-CUBE *Apr 24 12:41:23.551: unstructuredName:POC-CUBE *Apr 24 12:41:23.559: unstructuredName:POC-CUBE *Apr 24 12:41:23.559: unstructuredName:POC-CUBE *Apr 24 12:41:23.559: unstructuredName:POC-CUBE *Apr 24 12:41:23.559: unstructuredName:POC-CUBE POC-CUBE> POC-CUBE> POC-CUBE>en Password: POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE#show run | sec tru POC-CUBE#show run | sec trus POC-CUBE#show run | sec trus POC-CUBE#show run | sec trust POC-CUBE#show run | sec trustpoint crypto pki trustpoint callmg23 enrollment selfsigned subject-name CN=ASR1006-CCN-4 subject-alt-name 6961_SEC.cisco.com 8941_SEC.cisco.com 8945_SEC.cisco.com 7975_SEC.cisco.com 7970_SEC.cisco.com revocation-check crl rsakeypair pp1 crypto pki trustpoint self-trustpoint enrollment selfsigned serial-number subject-name CN=ASR1006-CCN-4 subject-alt-name 6961_SEC.cisco.com 8941_SEC.cisco.com 8945_SEC.cisco.com 7975_SEC.cisco.com 7970_SEC.cisco.com revocation-check crl rsakeypair pp1 crypto pki trustpoint cucm_trustpoint enrollment terminal revocation-check none crypto pki trustpoint capf-trustpoint enrollment terminal revocation-check none crypto pki certificate chain self-trustpoint certificate self-signed 02 --More--   3082023B 308201E5 A0030201 02020102 300D0609 2A864886 F70D0101 05050030 45311630 14060355 0403130D 41535231 3030362D 43434E2D 34312B30 12060355 0405130B 46474C31 35333231 31474D30 1506092A 864886F7 0D010902 1608504F 432D4355 4245301E 170D3134 30343234 31313232 33355A17 0D323030 31303130 30303030 305A3045 31163014 06035504 03130D41 53523130 30362D43 434E2D34 312B3012 06035504 05130B46 474C3135 33323131 474D3015 06092A86 4886F70D 01090216 08504F43 2D435542 45305C30 0D06092A 864886F7 0D010101 0500034B 00304802 4100BCB9 44DACD8A 93D98285 0E48D061 ADD02DC5 79E3A767 143FAD6E BAEB1162 CC58D4F8 4F16677A 4DB2E94D 7AEB1D02 5FCBC4CB B840AADD 01A80A7E E528A5FA 0CA10203 010001A3 81BF3081 BC300F06 03551D13 0101FF04 05300301 01FF3069 0603551D 11046230 60825E36 3936315F 5345432E 63697363 6F2E636F 6D203839 34315F53 45432E63 6973636F 2E636F6D 20383934 355F5345 432E6369 73636F2E 636F6D20 37393735 5F534543 2E636973 636F2E63 6F6D2037 3937305F 5345432E 63697363 6F2E636F 6D301F06 03551D23 04183016 8014F7DD 473488C5 8FFD2C77 7B39360A B5EE35CF 7797301D 0603551D 0E041604 14F7DD47 3488C58F FD2C777B 39360AB5 EE35CF77 97300D06 092A8648 86F70D01 01050500 03410071 B8319516 E7B71381 546A131A E88E7140 B5CB316F 196F61D0 15F642EA 77D38564 70D384B1 FB25536A 5876DBEF E12CCD94 85297839 3BDEACB8 41924702 0DBC52 quit crypto pki certificate chain cucm_trustpoint certificate ca 65CB0F226A12CE79A5D201FA3010C166 3082027E 308201E7 A0030201 02021065 CB0F226A 12CE79A5 D201FA30 10C16630 0D06092A 864886F7 0D010105 05003051 310B3009 06035504 06130241 45310C30 --More--   0A060355 040A1303 43495431 0B300906 0355040B 13024954 310D300B 06035504 03130443 55434D31 0B300906 03550408 13024144 310B3009 06035504 07130241 44301E17 0D313430 34313631 32323335 325A170D 31393034 31353132 32333531 5A305131 0B300906 03550406 13024145 310C300A 06035504 0A130343 4954310B 30090603 55040B13 02495431 0D300B06 03550403 13044355 434D310B 30090603 55040813 02414431 0B300906 03550407 13024144 30819F30 0D06092A 864886F7 0D010101 05000381 8D003081 89028181 00A4CCEB 0835A320 31DBF30A 350D0248 7D417923 57AAD756 A47A33E3 0573E41B 06D2FBF3 6011628F 4DA7083F 626A01BE C1C42B7D 9A986557 7191508B 5ABE92F6 C8DB0F83 3D831AB7 ECEFF750 5D4F908A 6A3301D5 082E2204 1D63D28A 3DFC0BA0 2984369A 8239C16B D7084FA4 24E04CE7 179AF9E8 282ED373 7AA56E44 DA256465 43020301 0001A357 3055300B 0603551D 0F040403 0202BC30 27060355 1D250420 301E0608 2B060105 05070301 06082B06 01050507 03020608 2B060105 05070305 301D0603 551D0E04 1604144F 458376D7 4BEA5BD9 3E50A153 4CA87981 678A2C30 0D06092A 864886F7 0D010105 05000381 81003D6A 10C715E6 3875DDFC 00B510EC 31DDBC5D 13D096FE 2E9B5A51 6B72FC97 41308C9A A2401379 9DB60912 4C5BFB4C 033B112E 5815759E 78502D8C 48858405 576BFB7E 2034E07F 5474EEB5 BF61599C 23D31CB2 FAB6B39F 50DD9D5C F10C0B99 284FB0C9 309A27BD 1CFFBA56 19B8008F C49D4974 17C5A041 C6B64F4C 200D07D6 8654 quit crypto pki certificate chain capf-trustpoint certificate ca 43A1B9FECFCF4E0082522208457E4B5A 30820286 308201EF A0030201 02021043 A1B9FECF CF4E0082 52220845 7E4B5A30 --More--   0D06092A 864886F7 0D010105 0500305A 310B3009 06035504 06130241 45310C30 0A060355 040A1303 43495431 0B300906 0355040B 13024954 31163014 06035504 03130D43 4150462D 64386666 35643736 310B3009 06035504 08130241 44310B30 09060355 04071302 4144301E 170D3134 30343136 31323233 35355A17 0D313930 34313531 32323335 345A305A 310B3009 06035504 06130241 45310C30 0A060355 040A1303 43495431 0B300906 0355040B 13024954 31163014 06035504 03130D43 4150462D 64386666 35643736 310B3009 06035504 08130241 44310B30 09060355 04071302 41443081 9F300D06 092A8648 86F70D01 01010500 03818D00 30818902 818100AA 7EA52B9C B768580F EB49D88B D9B2CE78 DBCBE93B E1EC10B0 09FB41E0 CC8A683B 20749DE6 D5CABD48 58652ABF 881AA22A 0D8F3497 00CBDE0D 668ADCF7 8CCE2E63 520F4213 8C456C40 70EC8C58 282B1F7A 394D6678 5D243BF8 16C15586 417726B6 E912FB61 4400195E 626BCFCF B20920A3 EBFD3206 DC35404D 293735BF 42715102 03010001 A34D304B 300B0603 551D0F04 04030202 A4301D06 03551D25 04163014 06082B06 01050507 03010608 2B060105 05070305 301D0603 551D0E04 1604140F BBA34EBB A1B780E3 113C4B53 51686EDB EC775430 0D06092A 864886F7 0D010105 05000381 81005D1D 17A69D10 5B28674E 0E960CCA 0BFAEFEF F72B9013 917267D3 4786B09A 6BEE2152 8889E894 51320C29 2A100346 9CC690CB 10380DA2 0EA8AF6A 43D5B039 20E58527 49FE3B6E A68A4E3C 82FACE9C DD6A0FEA 87B2813F B10C3A83 1367C144 7D6CCA1A 47AA649C FE98FAE2 1FC29760 07B538AA 3B3B9CFE E608FAD9 0CD1F9D8 54A1 quit record-entry selfsigned trustpoint self-trustpoint record-entry capf trustpoint capf-trustpoint --More--   record-entry cucm-tftp trustpoint cucm_trustpoint POC-CUBE# POC-CUBE# POC-CUBE#show run | sec dial-peer dial-peer voice 2 voip description DP_facing_CCM session protocol sipv2 session target ipv4:172.16.10.100 session transport tcp destination uri 1 incoming uri via 3 voice-class sip extension cucm voice-class sip call-route url voice-class sip profiles 11 voice-class sip pass-thru headers 10 voice-class sip copy-list 11 dtmf-relay rtp-nte codec transparent dial-peer voice 10 voip dial-peer voice 1 voip phone-proxy cubepp signal-addr ipv4 172.16.10.100 cucm ipv4 172.16.10.110 description DP_Access_Side session protocol sipv2 session target registrar session transport tcp tls destination uri 2 incoming uri request 1 --More--   voice-class sip call-route url voice-class sip profiles 10 voice-class sip registration passthrough registrar-index 1 voice-class sip pass-thru headers 10 voice-class sip copy-list 10 dtmf-relay rtp-nte codec transparent POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE#deb POC-CUBE#debug dia POC-CUBE#debug dial? dialer dialog-manager dialpeer POC-CUBE#debug dialp POC-CUBE#debug dialpeer ? POC-CUBE#debug dialpeer          voi POC-CUBE#debug voice POC-CUBE#debug voice dia POC-CUBE#debug voice dialpeer ? all Enable all debugs config config debug default Enable default debugs detail detail debug error major call and software errors debug function function debug inout inout debug POC-CUBE#debug voice dialpeer in POC-CUBE#debug voice dialpeer inout voip dialpeer inout debugging is on POC-CUBE#dep b POC-CUBE#debug ph POC-CUBE#debug pho POC-CUBE#debug pho   voice o pho POC-CUBE#debug voice phone-proxy all POC-CUBE#debug voice phone-proxy all phone proxy all debugging is on POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE#term POC-CUBE#terminal moni POC-CUBE#terminal monitor % Console already monitors POC-CUBE#\ % Bad IP address or host name % Unknown command or computer name, or unable to find computer address POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# *Apr 24 12:52:23.151: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:52:23.151: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 12:52:23.151: DBG: current hash elem num 1 *Apr 24 12:52:23.151: DBG: restart file buffer aging timer POC-CUBE# POC-CUBE# POC-CUBE# *Apr 24 12:53:23.151: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:53:23.151: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 12:53:23.151: DBG: current hash elem num 0 POC-CUBE# POC-CUBE# POC-CUBE#show run | sec dial-p dial-peer voice 2 voip description DP_facing_CCM session protocol sipv2 session target ipv4:172.16.10.100 session transport tcp destination uri 1 incoming uri via 3 voice-class sip extension cucm voice-class sip call-route url voice-class sip profiles 11 voice-class sip pass-thru headers 10 voice-class sip copy-list 11 dtmf-relay rtp-nte codec transparent dial-peer voice 10 voip dial-peer voice 1 voip phone-proxy cubepp signal-addr ipv4 172.16.10.100 cucm ipv4 172.16.10.110 description DP_Access_Side session protocol sipv2 session target registrar session transport tcp tls destination uri 2 incoming uri request 1 --More--   voice-class sip call-route url voice-class sip profiles 10 voice-class sip registration passthrough registrar-index 1 voice-class sip pass-thru headers 10 voice-class sip copy-list 10 dtmf-relay rtp-nte codec transparent POC-CUBE#cnf t ^ % Invalid input detected at '^' marker. POC-CUBE#conf *Apr 24 12:53:41.203: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:41.203: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:41.203: Phone proxy new phone event handler 1 *Apr 24 12:53:41.203: Phone proxy: TCP Socket accept *Apr 24 12:53:41.203: Create device hash table for phone 172.16.10.201 : 51352 *Apr 24 12:53:41.203: TCP access socket inherit fd:0 from fd:5 *Apr 24 12:53:41.203: pp_add_tcp_access_socket session 0x3D53:41.215: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:41.215: access socket recv http:GET /CTLSEPC40ACB4C9956.tlv HTTP/1.1 Host:172.16.10.100:6970 C82728 access side raddr:172.16.10.201, socket:0 *Apr 24 12: t *Apr 24 12:53:41.215: PP: Remote phone from 172.16.10.201/51352 requesting GET /CTLSEPC40ACB4C9956.tlv HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:41.215: get file name:CTLSEPC40ACB4C9956.tlv *Apr 24 12:53:41.215: this is ctl file *Apr 24 12:53:41.215: PP: send http ctl file *Apr 24 12:53:41.215: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4 *Apr 24 12:53:41.215: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4 *Apr 24 12:53:41.219: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:41.219: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:41.339: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:41.339: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:41.339: Phone proxy new phone event handler 1 *Apr 24 12:53:41.339: Phone proxy: TCP Socket accept *Apr 24 12:53:41.339: Create device hash table for phone 172.16.10.201 : 51353 *Apr 24 12:53:41.339: TCP access socket inherit fd:0 from fd:5 *Apr 24 12:53:41.339: pp_add_tcp_access_socket session 0x3DC82728 access side raddr:172.16.10.201, socket:0 *Apr 24 12:53:41.343: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:41.343: access socket recv http:GET /ITLSEPC40ACB4C9956.tlv HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:53:41.343: PP: Remote phone from 172.16.10.201/51353 requesting GET /ITLSEPC40ACB4C9956.tlv HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:41.343: get file name:ITLSEPC40ACB4C9956.tlv *Apr 24 12:53:41.343: DBG: check if filename(ITLSEPC40ACB4C9956.tlv) buffered *Apr 24 12:53:41.343: core side ip 172.16.10.110 session 0x3DC82728 *Apr 24 12:53:41.343: PP socket connect 1 remote addr 172.16.10.110 port 6970 *Apr 24 12:53:41.343: PP socket connect 1 remote addr 172.16.10.110 port 6970 in progress *Apr 24 12:53:41.343: pp_add_tcp_core_socket session 0x3DC82728 core side raddr:172.16.10.110, socket:1 *Apr 24 12:53:41.343: PP: modify http host, header:GET /ITLSEPC40ACB4C9956.tlv HTTP/1.1 Host:172.16.10.110:6970 , size: 65 *Apr 24 12:53:41.343: Phoneproxy tried to send data 0x3EEF2528 65 bytes, socket 1 sent nothing,tcp connect not finished *Apr 24 12:53:41.343: Got socket write event to resend on socket fd 1 resend queue:0x22D4E44C *Apr 24 12:53:41.343: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:41.343: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:41.343: http core side first pkg *Apr 24 12:53:41.343: http 200OK msg size:4517process http core side didn't finish,will continue process *Apr 24 12:53:41.343: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:41.343: DBG: check if filename(ITLSEPC40ACB4C9956.tlv) inspected *Apr 24 12:53:41.343: DBG: add buffer filename(ITLSEPC40ACB4C9956.tlv) size(4517) *Apr 24 12:53:41.343: DBG: start file buffer aging timer *Apr 24 12:53:41.343: DBG: update buffer filename(ITLSEPC40ACB4C9956.tlv), file completed(1) *Apr 24 12:53:41.343: DBG: file:ITLSEPC40ACB4C9956.tlv no registers *Apr 24 12:53:41.343: PP: Data sent from 172.16.10.100/6970 to remote phone at 172.16.10.201/51353 process http core side succ,delete socket *Apr 24 12:53:41.343: PP delete session:0x3DC82728, core:0, access:1 *Apr 24 12:53:41.347: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4 *Apr 24 12:53:41.347: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4 *Apr 24 12:53:41.351: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:41.351: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:41.351: Remove device hash table for phone 172.16.10.201 : 51353 *Apr 24 12:53:41.543: Phoneproxy_.201, socket:0 *Apr 24 12:53:41.543: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:41.543: access socket recv http:GET /SEPC40ACB4C9956.cnf.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:53:41.543: PP: Remote phone from 172.16.10.201/51354 requesting GET /SEPC40ACB4C9956.cnf.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:41.543: get file name:SEPC40ACB4C9956.cnf.xml.sgn mgt_process, unknown event. *Apr 24 12:53:41.543: Phoneproxy_ *Apr 24 12:53:41.543: core side ip 172.16.10.110 session 0x3DC82728 *Apr 24 12:53:41.547: PP socket connect 1 remote addr 172.16.10.110 port 6970 *Apr 24 12:53:41.547: PP socket connect 1 remote addr 172.16.10.110 port 6970 in progress *Apr 24 12:53:41.547: pp_add_tcp_core_socket session 0x3DC82728 core side raddr:172.16.10.110, socket:1 *Apr 24 12:53:41.547: PP: The remote phone is requesting file SEPC40ACB4C9956.cnf.xml.sgn. PP: Removing .sgn extension, SBC is requesting file SEPC40ACB4C9956.cnf.xml.sgn from Call Manager HTTP server. *Apr 24 12:53:41.547: PP: modify http host, header:GET /SEPC40ACB4C9956.cnf.xml HTTP/1.1 Host:172.16.10.110:6970 , size: 66mgt_process, unknown event. *Apr 24 12:53:41.543: Phone proxyirst pkg *Apr 24 12:53:41.547: http 200OK msg size:12359process http core side didn't finish,will continue process *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728process http core side didn't finish,will continue process *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728process http core side didn't finish,will continue process *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 new phone event handler 1 *Apr 24 12:53:41.543: Phone proxy: Tprocess http core side didn't finish,will continue process *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728process http core side didn't finish,will continue process *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728process http core side didn't finish,will continue process *Apr 24 12:53:41.551: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:41.551: PP: Complete configuration file received from Call Manager TFTP server, beginning config file modification process. *Apr 24 12:53:41.551: PP: Config Modify: rewriting addr to 172.16.10.110 *Apr 24 12:53:41.551: PP: Config Modify: rewriting addr to 0.0.0.0 CP Socket accept *Apr 24 12:53:41.543: Create device hash tableting addr to 172.16.10.110 *Apr 24 12:53:41.551: PP: Config Modify: rewriting addr to 0.0.0.0 *Apr 24 12:53:41.551: PP: CM Name Config Modify : detected Call Manager Name. *Apr 24 12:53:41.551: PP: Config Modify: rewriting port 5060 to 5060 *Apr 24 12:53:41.551: PP: Config Modify: rewriting port 5061 to 5061 *Apr 24 12:53:41.551: PP: Config Modify: rewriting addr to 172.16.10.110 *Apr 24 12:53:41.551: PP: Config Modify: rewriting addr to 0.0.0.0 *Apr 24 12:53:41.551: PP: Config Modify : detected Call Manager Node Name. for phone 172.16.10.201 : 51354 *Apr 24 12:53:41.543: TCP acce *Apr 24 12:53:41.551: PP: Config Modify : Phone already in nonsecure mode (1), not modifying deviceSecurityMode. *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.551: PP: Config Modify : transportLayerProtocol is already TCP ss socket inherit fd:0 from fd:5 *Apr 24 12:53:41.543: pp_add_t.0.0 *Apr 24 12:53:41.551: PP:CAPF Config Modify : detected Call Manager Node Name. Enter ctl_sign PP: Encrypting and signing modified config file using trustpoint . ctl_sign: total_file_len=12652, signature_len = 64 *Apr 24 12:53:41.563: PP: Data sent from 172.16.10.100/6970 to remote phone at 172.16.10.201/51354 process http core side succ,delete socket *Apr 24 12:53:41.563: PP delete session:0x3DC82728, core:0, access:1 *Apr 24 12:53:41.563: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4cp_access_socket session 0x3DC82728 access side raddr:172.16.10 *Apr 24 12:53:41.563: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4 *Apr 24 12:53:41.567: Got socket write event to resend on socket fd 0 resend queue:0x22D4E3D4 *Apr 24 12:53:41.571: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:41.571: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:41.571: Remove device hash table for phone 172.16.10.201 : 51354 *Apr 24 12:53:41.639: Phone proxy new phone event handler 0 *Apr 24 12:53:41.639: PP: Remote phone from 172.16.10.201/49152 requesting SEPC40ACB4C9956.cnf.xml.sgn at 172.16.10.100/69 *Apr 24 12:53:41.639: DBG: check if filename(SEPC40ACB4C9956.cnf.xml.sgn) buffered *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler sock:1 sessing config file SEPC40ACB4C9956.cnf.xml.sgn. *Apr 24 12:53:41.639: Create device hash table for phone 172.16.10.201 : 49152 *Apr 24 12:53:41.639: PP: The remote phone is requesting file SEPC40ACB4C9956.cnf.xml.sgn. PP: Removing .sgn extension, SBC is requesting file SEPC40ACB4C9956.cnf.xml from Call Manager TFTP server. *Apr 24 12:53:41.643: PP: Intercepted Data Block 1 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.643: PP: SBC sending TFTP Ack for Block #1 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 on:3DC82728 *Apr 24 12:53:41.547: PP: pp_sock_tcp_read_handler *Apr 24 12:53:41.643: PP: Intercepted Data Block 2 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.643: PP: SBC sending TFTP Ack for Block #2 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.643: PP: Intercepted Data Block 3 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.643: PP: SBC sending TFTP Ack for Block #3 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.643: PP: Intercepted Data Block 4 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.643: PP: SBC sending TFTP Ack for Block #4 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 sock:1 session:3DC82728 *Apr 24 12:53:41.547: http core side f TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.643: PP: Intercepted Data Block 6 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.643: PP: SBC sending TFTP Ack for Block #6 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.643: PP: Intercepted Data Block 7 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: PP: SBC sending TFTP Ack for Block #7 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.551: PP: CM Name Config Modify : detected Call *Apr 24 12:53:41.647: PP: Intercepted Data Block 8 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: PP: SBC sending TFTP Ack for Block #8 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.647: PP: Intercepted Data Block 9 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: PP: SBC sending TFTP Ack for Block #9 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.647: PP: Intercepted Data Block 10 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: PP: SBC sending TFTP Ack for Block #10 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 Manager Name. *Apr 24 12:53:41.551: PP: Config Modify: rewri P: SBC sending TFTP Ack for Block #11 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.647: PP: Intercepted Data Block 12 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: PP: SBC sending TFTP Ack for Block #12 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.647: PP: Intercepted Data Block 13 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: PP: SBC sending TFTP Ack for Block #13 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.551: PP: Config Modify: rewriting addr to 0.0 *Apr 24 12:53:41.651: PP: Intercepted Data Block 14 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.651: PP: SBC sending TFTP Ack for Block #14 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.651: PP: Intercepted Data Block 15 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.651: PP: SBC sending TFTP Ack for Block #15 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.651: PP: Intercepted Data Block 16 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.651: PP: SBC sending TFTP Ack for Block #16 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.639: core side ip 172.16.10.110 session 0x3DCP: SBC sending TFTP Ack for Block #17 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.651: PP: Intercepted Data Block 18 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.651: PP: SBC sending TFTP Ack for Block #18 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.651: PP: Intercepted Data Block 19 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.651: PP: SBC sending TFTP Ack for Block #19 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 82728 *Apr 24 12:53:41.639: PP: The remote phone is requesti *Apr 24 12:53:41.643: PP: Intercepted Data Block 5 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.643: PP: SBC sending TFTP Ack for Block #5 from 172.16.10.100/40012 to *Apr 24 12:53:41.647: PP: Intercepted Data Block 11 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.647: P *Apr 24 12:53:41.651: PP: Intercepted Data Block 17 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.651: P *Apr 24 12:53:41.651: PP: Intercepted Data Block 20 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.655: PP: SBC sending TFTP Ack for Block #20 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.655: PP: Intercepted Data Block 21 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.655: PP: SBC sending TFTP Ack for Block #21 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.655: PP: Intercepted Data Block 22 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.655: PP: SBC sending TFTP Ack for Block #22 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.655: PP: Intercepted Data Block 23 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.655: PP: SBC sending TFTP Ack for Block #23 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.655: PP: Intercepted Data Block 24 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.655: PP: SBC sending TFTP Ack for Block #24 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.655: PP: Intercepted Data Block 25 from 172.16.10.110/46662 to 172.16.10.100/40012 *Apr 24 12:53:41.655: PP: SBC sending TFTP Ack for Block #25 from 172.16.10.100/40012 to TFTP server 172.16.10.110/46662 *Apr 24 12:53:41.655: PP: Complete configuration file received from Call Manager TFTP server, beginning config file modification process. *Apr 24 12:53:41.655: PP: Config Modify: rewriting addr to 172.16.10.110 *Apr 24 12:53:41.655: PP: Config Modify: rewriting addr to 0.0.0.0 *Apr 24 12:53:41.655: PP: CM Name Config Modify : detected Call Manager Name. *Apr 24 12:53:41.655: PP: Config Modify: rewriting addr to 172.16.10.110 *Apr 24 12:53:41.655: PP: Config Modify: rewriting addr to 0.0.0.0 *Apr 24 12:53:41.655: PP: CM Name Config Modify : detected Call Manager Name. *Apr 24 12:53:41.655: PP: Config Modify: rewriting port 5060 to 5060 *Apr 24 12:53:41.655: PP: Config Modify: rewriting port 5061 to 5061 *Apr 24 ll Manager Node Name. *Apr 24 12:53:41.659: PP: Config Modify : Phone already in nonsecure mode (1), not modifying deviceSecurityMode. *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr12:53:41.655: PP: Config Modify: rewriting addr to 172.16.10.110 *Apr 24 12:53:41.655: PP: Config Modify: rewriting addr to 0.0.0.0 *Apr 24 12:53:41.655: PP: Config Modify : detected Ca *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Url ipaddr invalid ip addr *Apr 24 12:53:41.659: PP: Config Modify : transportLayerProtocol is already TCP *Apr 24 12:53:41.659: PP: Config Modify: rewriting addr to 0.0.0.0 *Apr 24 12:53:41.659: PP:CAPF Config Modify : detected Call Manager Node Name. Enter ctl_sign PP: Encrypting and signing modified config file using trustpoint . ctl_sign: total_file_len=12652, signature_len = 64 *Apr 24 12:53:41.671: PP: Data Block 1 sent from SBC at 172.16.10.100/40011 to remote phone at 172.16.10.201/49152 size(516) *Apr 24 12:53:41.671: PP: Received ACK for Block 1 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.671: PP: Data Block 2 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.671: PP: Received ACK for Block 2 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.671: PP: Data Block 3 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.675: PP: Received ACK for Block 3 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.675: PP: Data Block 4 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.675: PP: Received ACK for Block 4 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.675: PP: Data Block 5 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.679: PP: Received ACK for Block 5 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.679: PP: Data Block 6 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.679: PP: Received ACK for Block 6 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.679: PP: Data Block 7 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.679: PP: Received ACK for Block 7 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.679: PP: Data Block 8 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.687: PP: Received ACK for Block 8 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.687: PP: Data Block 9 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.691: PP: Received ACK for Block 9 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.691: PP: Data Block 10 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.691: PP: Received ACK for Block 10 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.691: PP: Data Block 11 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.691: PP: Received ACK for Block 11 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.691: PP: Data Block 12 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.691: PP: Received ACK for Block 12 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.691: PP: Data Block 13 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.695: PP: Received ACK for Block 13 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.695: PP: Data Block 14 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.695: PP: Received ACK for Block 14 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.695: PP: Data Block 15 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.699: PP: Received ACK for Block 15 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.699: PP: Data Block 16 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.699: PP: Received ACK for Block 16 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.699: PP: Data Block 17 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.699: PP: Received ACK for Block 17 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.699: PP: Data Block 18 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.699: PP: Received ACK for Block 18 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.699: PP: Data Block 19 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.703: PP: Received ACK for Block 19 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.703: PP: Data Block 20 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.703: PP: Received ACK for Block 20 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.703: PP: Data Block 21 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.703: PP: Received ACK for Block 21 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.703: PP: Data Block 22 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.703: PP: Received ACK for Block 22 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.703: PP: Data Block 23 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.707: PP: Received ACK for Block 23 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.707: PP: Data Block 24 sent from SBC to remote phone at 172.16.10.100/40011 size(516) *Apr 24 12:53:41.707: PP: Received ACK for Block 24 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.707: PP: Data Block 25 sent from SBC to remote phone at 172.16.10.100/40011 size(368) *Apr 24 12:53:41.707: PP: Received ACK for Block 25 from remote phone 172.16.10.201/49152 to SBC at 172.16.10.100/40011 *Apr 24 12:53:41.707: PP: TFTP session complete, config file SEPC40ACB4C9956.cnf.xml.sgn has been successfully sent to the remote phone. *Apr 24 12:53:41.707: PP delete session:0x3DC82728, core:1, access:0 *Apr 24 12:53:42.191: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:42.191: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:42.191: Phone proxy new phone event handler 1 *Apr 24 12:53:42.191: Phone proxy: TCP Socket accept *Apr 24 12:53:42.191: Create device hash table for phone 172.16.10.201 : 51355 *Apr 24 12:53:42.195: TCP access socket inherit fd:0 from fd:5 *Apr 24 12:53:42.195: pp_add_tcp_access_socket session 0x3DC82640 access side raddr:172.16.10.201, socket:0 *Apr 24 12:53:42.331: PP: pp_sock_tcp_read_handler sock:0 session:3DC82640 *Apr 24 12:53:42.331: access socket recv http:GET /DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:53:42.331: PP: Remote phone from 172.16.10.201/51355 requesting GET /DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:42.331: get file name:DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn *Apr 24 12:53:42.331: DBG: check if filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) buffered *Apr 24 12:53:42.331: core side ip 172.16.10.110 session 0x3DC82640 *Apr 24 12:53:42.331: PP socket connect 2 remote addr 172.16.10.110 port 6970 *Apr 24 12:53:42.331: PP socket connect 2 remote addr 172.16.10.110 port 6970 in progress *Apr 24 12:53:42.331: pp_add_tcp_core_socket session 0x3DC82640 core side raddr:172.16.10.110, socket:2 *Apr 24 12:53:42.331: PP: modify http host, header:GET /DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn HTTP/1.1 Host:172.16.10.110:6970 , size: 107 *Apr 24 12:53:42.331: Phoneproxy tried to send data 0x3EEF2528 107 bytes, socket 2 sent nothing,tcp connect not finished *Apr 24 12:53:42.331: Got socket write event to resend on socket fd 2 resend queue:0x3EFC92FC *Apr 24 12:53:42.331: PP: pp_sock_tcp_read_handler sock:2 session:3DC82640 *Apr 24 12:53:42.331: PP: pp_sock_tcp_read_handler sock:2 session:3DC82640 *Apr 24 12:53:42.331: http core side first pkg *Apr 24 12:53:42.331: http 200OK msg size:1839process http core side didn't finish,will continue process *Apr 24 12:53:42.331: PP: pp_sock_tcp_read_handler sock:2 session:3DC82640 *Apr 24 12:53:42.331: DBG: check if filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) inspected *Apr 24 12:53:42.331: DBG: add buffer filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) size(1839) *Apr 24 12:53:42.331: DBG: update buffer filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn), file completed(1) *Apr 24 12:53:42.331: DBG: file:DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn no registers *Apr 24 12:53:42.331: PP: Data sent from 172.16.10.100/6970 to remote phone at 172.16.10.201/51355 process http core side succ,delete socket *Apr 24 12:53:42.331: PP delete session:0x3DC82640, core:0, access:1 *Apr 24 12:53:42.335: Got socket write event to resend on socket fd 0 resend queue:0x3EFC93EC *Apr 24 12:53:42.367: PP: pp_sock_tcp_read_handler sock:0 session:3DC82640 *Apr 24 12:53:42.367: PP delete session:0x3DC82640, core:0, access:0 *Apr 24 12:53:42.367: Remove device hash table for phone 172.16.10.201 : 51355 *Apr 24 12:53:42.487: //-1/4C6F0263802A/DPM/dpAssociateIncomingPeerCore: Calling Number=c40acb4c9956, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.487: //-1/4C6F0263802A/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:53:42.487: //-1/4C6F0263802A/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:53:42.491: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.491: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:172.16.10.100 *Apr 24 12:53:42.491: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:53:42.491: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:53:42.491: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=2 2: Dial-peer Tag=1 *Apr 24 12:53:42.495: //-1/4C703AB3802C/DPM/dpAssociateIncomingPeerCore: Calling Number=c40acb4c9956, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.495: //-1/4C703AB3802C/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:53:42.495: //-1/4C703AB3802C/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:53:42.495: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.495: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:172.16.10.100 *Apr 24 12:53:42.495: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:53:42.495: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:53:42.495: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=2 2: Dial-peer Tag=1 *Apr 24 12:53:42.499: //-1/4C703AB3802D/DPM/dpAssociateIncomingPeerCore: Calling Number=c40acb4c9956, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.499: //-1/4C703AB3802D/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:53:42.499: //-1/4C703AB3802D/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:53:42.499: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.499: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:172.16.10.100 *Apr 24 12:53:42.499: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:53:42.499: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:53:42.499: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=2 2: Dial-peer Tag=1 *Apr 24 12:53:42.503: //-1/4C7173038030/DPM/dpAssociateIncomingPeerCore: Calling Number=c40acb4c9956, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.503: //-1/4C7173038030/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:53:42.503: //-1/4C7173038030/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:172.16.10.100 *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=2 2: Dial-peer Tag=1 *Apr 24 12:53:42.507: //-1/4C720F2B8031/DPM/dpAssociateIncomingPeerCore: Calling Number=c40acb4c9956, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.507: //-1/4C720F2B8031/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:53:42.507: //-1/4C720F2B8031/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.507: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:172.16.10.100 *Apr 24 12:53:42.523: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:53:42.527: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:53:42.527: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=1 2: Dial-peer Tag=2 *Apr 24 12:53:42.839: //-1/4C7D0BC48038/DPM/dpAssociateIncomingPeerCore: Calling Number=4444, Called Number=4444, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:53:42.839: //-1/4C7D0BC48038/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:53:42.839: //-1/4C7D0BC48038/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:53:43.867: pp: core side last ack for session 3DC82728 delay close timer expired *Apr 24 12:53:43.867: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:50.075: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:50.075: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:50.075: Phone proxy new phone event handler 1 *Apr 24 12:53:50.075: Phone proxy: TCP Socket accept *Apr 24 12:53:50.075: Create device hash table for phone 172.16.10.202 : 52882 *Apr 24 12:53:50.075: TCP access socket inherit fd:0 from fd:5 *Apr 24 12:53:50.075: pp_add_tcp_access_socket session 0x3DC82728 access side raddr:172.16.10.202, socket:0 *Apr 24 12:53:50.075: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:50.075: access socket recv http:GET /CTLSEPC40ACB4C9995.tlv HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:53:50.075: PP: Remote phone from 172.16.10.202/52882 requesting GET /CTLSEPC40ACB4C9995.tlv HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:50.075: get file name:CTLSEPC40ACB4C9995.tlv *Apr 24 12:53:50.075: this is ctl file *Apr 24 12:53:50.075: PP: send http ctl file *Apr 24 12:53:50.079: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:50.079: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:50.083: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:50.083: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:50.171: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:50.171: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:50.171: Phone proxy new phone event handler 1 *Apr 24 12:53:50.171: Phone proxy: TCP Socket accept *Apr 24 12:53:50.171: Create device hash table for phone 172.16.10.202 : 52883 *Apr 24 12:53:50.171: TCP access socket inherit fd:0 from fd:5 *Apr 24 12:53:50.171: pp_add_tcp_access_socket session 0x3DC82728 access side raddr:172.16.10.202, socket:0 *Apr 24 12:53:50.175: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:50.175: access socket recv http:GET /ITLSEPC40ACB4C9995.tlv HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:53:50.175: PP: Remote phone from 172.16.10.202/52883 requesting GET /ITLSEPC40ACB4C9995.tlv HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:50.175: get file name:ITLSEPC40ACB4C9995.tlv *Apr 24 12:53:50.175: DBG: check if filename(ITLSEPC40ACB4C9995.tlv) buffered *Apr 24 12:53:50.175: core side ip 172.16.10.110 session 0x3DC82728 *Apr 24 12:53:50.175: PP socket connect 1 remote addr 172.16.10.110 port 6970 *Apr 24 12:53:50.175: PP socket connect 1 remote addr 172.16.10.110 port 6970 in progress *Apr 24 12:53:50.175: pp_add_tcp_core_socket session 0x3DC82728 core side raddr:172.16.10.110, socket:1 *Apr 24 12:53:50.175: PP: modify http host, header:GET /ITLSEPC40ACB4C9995.tlv HTTP/1.1 Host:172.16.10.110:6970 , size: 65 *Apr 24 12:53:50.175: Phoneproxy tried to send data 0x3EEF2528 65 bytes, socket 1 sent nothing,tcp connect not finished *Apr 24 12:53:50.175: Got socket write event to resend on socket fd 1 resend queue:0x22D4E3D4 *Apr 24 12:53:50.175: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:50.179: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:50.179: http core side first pkg *Apr 24 12:53:50.179: http 200OK msg size:4517process http core side didn't finish,will continue process *Apr 24 12:53:50.179: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:50.179: DBG: check if filename(ITLSEPC40ACB4C9995.tlv) inspected *Apr 24 12:53:50.179: DBG: add buffer filename(ITLSEPC40ACB4C9995.tlv) size(4517) *Apr 24 12:53:50.179: DBG: update buffer filename(ITLSEPC40ACB4C9995.tlv), file completed(1) *Apr 24 12:53:50.179: DBG: file:ITLSEPC40ACB4C9995.tlv no registers *Apr 24 12:53:50.179: PP: Data sent from 172.16.10.100/6970 to remote phone at 172.16.10.202/52883 process http core side succ,delete socket *Apr 24 12:53:50.179: PP delete session:0x3DC82728, core:0, access:1 *Apr 24 12:53:50.739: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:50.743: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:51.611: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:51.611: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:51.611: Remove device hash table for phone 172.16.10.202 : 52883 *Apr 24 12:53:51.615: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:51.615: Phoneproxy_mgt_process, unknown event. *Apr 24 12:53:51.615: Phone proxy new phone event handler 1 *Apr 24 12:53:51.615: Phone proxy: TCP Socket accept *Apr 24 12:53:51.615: Create device hash table for phone 172.16.10.202 : 52884 *Apr 24 12:53:51.619: TCP access socket inherit fd:0 from fd:5 *Apr 24 12:53:51.619: pp_add_tcp_access_socket session 0x3DC82728 access side raddr:172.16.10.202, socket:0 *Apr 24 12:53:52.211: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:52.211: access socket recv http:GET /SEPC40ACB4C9995.cnf.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:53:52.211: PP: Remote phone from 172.16.10.202/52884 requesting GET /SEPC40ACB4C9995.cnf.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:53:52.211: get file name:SEPC40ACB4C9995.cnf.xml.sgn *Apr 24 12:53:52.215: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:53.075: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:53.075: http core side first pkg *Apr 24 12:53:53.075: http 200OK msg size:12359process http core side didn't finish,will continue process *Apr 24 12:53:53.079: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728process http core side didn't finish,will continue process *Apr 24 12:53:53.079: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728process http core side didn't finish,will continue process *Apr 24 12:53:53.079: PP: pp_sock_tcp_read_handler sock:1 session:3DC82728 *Apr 24 12:53:53.095: PP delete session:0x3DC82728, core:0, access:1 *Apr 24 12:53:53.095: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:53.771: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:53.775: Got socket write event to resend on socket fd 0 resend queue:0x22D4E44C *Apr 24 12:53:53.775: PP: pp_sock_tcp_read_handler sock:0 session:3DC82728 *Apr 24 12:53:53.775: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:53:53.775: Remove device hash table for phone 172.16.10.202 : 52884 *Apr 24 12:53:53.839: Phone proxy new phone event handler 0 *Apr 24 12:53:53.839: PP: Remote phone from 172.16.10.202/49152 requesting SEPC40ACB4C9995.cnf.xml.sgn at 172.16.10.100/69 *Apr 24 12:53:53.839: DBG: check if filename(SEPC40ACB4C9995.cnf.xml.sgn) buffered *Apr 24 12:53:53.839: core side ip 172.16.10.110 session 0x3DC82728 *Apr 24 12:53:53.839: PP: The remote phone is requesting config file SEPC40ACB4C9995.cnf.xml.sgn. *Apr 24 12:53:53.839: Create device hash table for phone 172.16.10.202 : 49152 *Apr 24 12:53:53.839: PP: The remote phone is requesting file SEPC40ACB4C9995.cnf.xml.sgn. PP: Removing .sgn extension, SBC is requesting file SEPC40ACB4C9995.cnf.xml from Call Manager TFTP server. *Apr 24 12:53:54.567: PP: Intercepted Data Block 1 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:54.567: PP: SBC sending TFTP Ack for Block #1 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:55.271: PP: Intercepted Data Block 2 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:55.271: PP: SBC sending TFTP Ack for Block #2 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:55.271: PP: Intercepted Data Block 3 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:55.271: PP: SBC sending TFTP Ack for Block #3 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:55.271: PP: Intercepted Data Block 4 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:55.271: PP: SBC sending TFTP Ack for Block #4 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:56.131: PP: Intercepted Data Block 5 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:56.131: PP: SBC sending TFTP Ack for Block #5 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:56.135: PP: Intercepted Data Block 6 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:56.135: PP: SBC sending TFTP Ack for Block #6 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:56.135: PP: Intercepted Data Block 7 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:56.135: PP: SBC sending TFTP Ack for Block #7 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:56.835: PP: Intercepted Data Block 8 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:56.835: PP: SBC sending TFTP Ack for Block #8 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:56.839: PP: Intercepted Data Block 9 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:56.839: PP: SBC sending TFTP Ack for Block #9 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:56.839: PP: Intercepted Data Block 10 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:56.839: PP: SBC sending TFTP Ack for Block #10 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:57.683: PP: Intercepted Data Block 11 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:57.683: PP: SBC sending TFTP Ack for Block #11 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:57.687: PP: Intercepted Data Block 12 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:57.687: PP: SBC sending TFTP Ack for Block #12 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:57.687: PP: Intercepted Data Block 13 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:57.687: PP: SBC sending TFTP Ack for Block #13 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:58.311: PP: Intercepted Data Block 14 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:58.311: PP: SBC sending TFTP Ack for Block #14 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:58.311: PP: Intercepted Data Block 15 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:58.311: PP: SBC sending TFTP Ack for Block #15 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:58.311: PP: Intercepted Data Block 16 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:58.311: PP: SBC sending TFTP Ack for Block #16 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:59.159: PP: Intercepted Data Block 17 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:59.159: PP: SBC sending TFTP Ack for Block #17 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:59.163: PP: Intercepted Data Block 18 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:59.163: PP: SBC sending TFTP Ack for Block #18 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:59.163: PP: Intercepted Data Block 19 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:59.163: PP: SBC sending TFTP Ack for Block #19 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:59.867: PP: Intercepted Data Block 20 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:59.867: PP: SBC sending TFTP Ack for Block #20 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:59.867: PP: Intercepted Data Block 21 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:59.867: PP: SBC sending TFTP Ack for Block #21 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:53:59.867: PP: Intercepted Data Block 22 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:53:59.867: PP: SBC sending TFTP Ack for Block #22 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:54:00.719: PP: Intercepted Data Block 23 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:54:00.719: PP: SBC sending TFTP Ack for Block #23 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 *Apr 24 12:54:00.723: PP: Intercepted Data Block 24 from 172.16.10.110/33874 to 172.16.10.100/40017 *Apr 24 12:54:00.723: PP: SBC sending TFTP Ack for Block #24 from 172.16.10.100/40017 to TFTP server 172.16.10.110/33874 PP: Encrypting and signing modified config file using trustpoint . ctl_sign: total_file_len=12652, signature_len = 64 *Apr 24 12:54:01.427: PP: Data Block 1 sent from SBC at 172.16.10.100/40016 to remote phone at 172.16.10.202/49152 size(516) *Apr 24 12:54:03.427: pp: core side last ack for session 3DC82728 delay close timer expired *Apr 24 12:54:03.427: PP delete session:0x3DC82728, core:0, access:1 *Apr 24 12:54:06.047: Phoneproxy_mgt_process, unknown event. *Apr 24 12:54:06.051: Phoneproxy_mgt_process, unknown event. *Apr 24 12:54:06.051: Phone proxy new phone event handler 1 *Apr 24 12:54:06.051: Phone proxy: TCP Socket accept *Apr 24 12:54:06.051: Create device hash table for phone 172.16.10.202 : 52885 *Apr 24 12:54:06.051: TCP access socket inherit fd:1 from fd:5 *Apr 24 12:54:06.051: pp_add_tcp_access_socket session 0x3DC82640 access side raddr:172.16.10.202, socket:1 *Apr 24 12:54:06.051: PP: pp_sock_tcp_read_handler sock:1 session:3DC82640 *Apr 24 12:54:06.051: access socket recv http:GET /DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 *Apr 24 12:54:06.051: PP: Remote phone from 172.16.10.202/52885 requesting GET /DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn HTTP/1.1 Host:172.16.10.100:6970 at 172.16.10.100/6970 *Apr 24 12:54:06.051: get file name:DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn *Apr 24 12:54:06.051: DBG: check if filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) buffered *Apr 24 12:54:06.051: DBG: filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) has been buffered *Apr 24 12:54:06.051: DBG: register filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) event(2) session(0x3DC82640) callback *Apr 24 12:54:06.051: DBG: register filename(DefaultFP0000000000-c7a6c673-7479-46b0-839e-014d3d093963.xml.sgn) event(1) session(0x3DC82640) callback *Apr 24 12:54:06.051: PP: Data sent from 172.16.10.100/6970 to remote phone at 172.16.10.202/52885 *Apr 24 12:54:06.551: Got socket write event to resend on socket fd 1 resend queue:0x3EEB6FCC *Apr 24 12:54:11.563: pp: wait access side ack for session 0x3DC82728 expired ! *Apr 24 12:54:11.563: PP: Tftp resend block #1 *Apr 24 12:54:16.563: pp: wait access side ack for session 0x3DC82728 expired ! *Apr 24 12:54:16.563: PP: Tftp resend block #1 *Apr 24 12:54:21.563: pp: wait access side ack for session 0x3DC82728 expired ! *Apr 24 12:54:21.563: PP delete session:0x3DC82728, core:0, access:0 *Apr 24 12:54:21.563: DBG: free tftp usr data :SEPC40ACB4C9995.cnf.xml.sgn 0 *Apr 24 12:54:21.563: Remove device hash table for phone 172.16.10.202 : 49152 *Apr 24 12:54:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:54:41.343: DBG: file buffer aging timeout, buffer total_size(10873), file_num(3) *Apr 24 12:54:41.343: DBG: current hash elem num 3 *Apr 24 12:54:41.343: DBG: restart file buffer aging timer Enter configuration commands, one per line. End with CNTL/Z. POC-CUBE(config)# POC-CUBE(config)# POC-CUBE(config)# POC-CUBE(config)# POC-CUBE(config)# POC-CUBE(config)# POC-CUBE(config)# POC-CUBE(config)#^Z POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# *Apr 24 12:55:14.311: %SYS-5-CONFIG_I: Configured from console by console *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=4444, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=4444 *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1) *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=4444, saf_enabled=1, saf_dndb_lookup=1, dp_result=-1 *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=NO_MATCH(-1) *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=4445, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=NO_MATCH(-1) After All Match Rules Attempt *Apr 24 12:55:21.703: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1 *Apr 24 12:55:21.707: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=4445, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.707: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=NO_MATCH(-1) After All Match Rules Attempt *Apr 24 12:55:21.707: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1 *Apr 24 12:55:21.707: //-1/87922A7D8040/DPM/dpAssociateIncomingPeerCore: Calling Number=4445, Called Number=4444, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.707: //-1/87922A7D8040/DPM/dpAssociateIncomingPeerCore: Result=Success(0) after DP_MATCH_REQUEST_URI; Incoming Dial-peer=1 *Apr 24 12:55:21.707: //-1/87922A7D8040/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=0 *Apr 24 12:55:21.707: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp: Tag=1 *Apr 24 12:55:21.707: //-1/xxxxxxxxxxxx/DPM/dpGetSIPUriProvisionBmp: Result=0 Bitmap=0x0 *Apr 24 12:55:21.711: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=4445, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.711: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=NO_MATCH(-1) After All Match Rules Attempt *Apr 24 12:55:21.711: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1 *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersCore: Calling Number=, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=4444 *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersCore: No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1) *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchSafModulePlugin: dialstring=4444, saf_enabled=0, saf_dndb_lookup=1, dp_result=-1 *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersMoreArg: Result=NO_MATCH(-1) *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersCore: Calling Number=, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:4444@172.16.10.100;user=phone *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchSafModulePlugin: dialstring=4444, saf_enabled=0, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:55:21.711: //-1/87922A7D8040/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=1 2: Dial-peer Tag=2 *Apr 24 12:55:21.711: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=4444, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=4444 *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1) *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=4444, saf_enabled=0, saf_dndb_lookup=1, dp_result=-1 *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=NO_MATCH(-1) *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=4444, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=NO_MATCH(-1) After All Match Rules Attempt *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1 *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Calling Number=4444, Called Number=, Voice-Interface=0x0, Timeout=TRUE, Peer Encap Type=ENCAP_VOIP, Peer Search Type=PEER_TYPE_VOICE, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpAssociateIncomingPeerCore: Result=NO_MATCH(-1) After All Match Rules Attempt *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=NULL, saf_enabled=0, saf_dndb_lookup=0, dp_result=-1 *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Calling Number=, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST; Called Number=4444 *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersCore: No Outgoing Dial-peer Is Matched; Result=NO_MATCH(-1) *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchSafModulePlugin: dialstring=4444, saf_enabled=0, saf_dndb_lookup=1, dp_result=-1 *Apr 24 12:55:21.715: //-1/xxxxxxxxxxxx/DPM/dpMatchPeersMoreArg: Result=NO_MATCH(-1) *Apr 24 12:55:21.715: //-1/87922A7D8040/DPM/dpMatchPeersCore: Calling Number=, Called Number=4444, Peer Info Type=DIALPEER_INFO_SPEECH *Apr 24 12:55:21.715: //-1/87922A7D8040/DPM/dpMatchPeersCore: Match Rule=DP_MATCH_DEST_URI; URI=sip:4444@172.16.10.100;user=phone *Apr 24 12:55:21.715: //-1/87922A7D8040/DPM/dpMatchPeersCore: Result=Success(0) after DP_MATCH_DEST_URI *Apr 24 12:55:21.715: //-1/87922A7D8040/DPM/dpMatchSafModulePlugin: dialstring=4444, saf_enabled=1, saf_dndb_lookup=1, dp_result=0 *Apr 24 12:55:21.715: //-1/87922A7D8040/DPM/dpMatchPeersMoreArg: Result=SUCCESS(0) List of Matched Outgoing Dial-peer(s): 1: Dial-peer Tag=1 2: Dial-peer Tag=2 *Apr 24 12:55:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:55:41.343: DBG: file buffer aging timeout, buffer total_size(10873), file_num(3) *Apr 24 12:55:41.343: DBG: current hash elem num 3 *Apr 24 12:55:41.343: DBG: restart file buffer aging timer POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# *Apr 24 12:56:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:56:41.343: DBG: file buffer aging timeout, buffer total_size(10873), file_num(3) *Apr 24 12:56:41.343: DBG: current hash elem num 1 *Apr 24 12:56:41.343: DBG: restart file buffer aging timer *Apr 24 12:57:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:57:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 12:57:41.343: DBG: current hash elem num 1 *Apr 24 12:57:41.343: DBG: restart file buffer aging timer *Apr 24 12:58:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:58:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 12:58:41.343: DBG: current hash elem num 1 *Apr 24 12:58:41.343: DBG: restart file buffer aging timer *Apr 24 12:59:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 12:59:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 12:59:41.343: DBG: current hash elem num 1 *Apr 24 12:59:41.343: DBG: restart file buffer aging timer *Apr 24 13:00:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 13:00:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 13:00:41.343: DBG: current hash elem num 1 *Apr 24 13:00:41.343: DBG: restart file buffer aging timer POC-CUBE# POC-CUBE# POC-CUBE# POC-CUBE# *Apr 24 13:01:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 13:01:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 13:01:41.343: DBG: current hash elem num 1 *Apr 24 13:01:41.343: DBG: restart file buffer aging timer *Apr 24 13:02:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 13:02:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 13:02:41.343: DBG: current hash elem num 1 *Apr 24 13:02:41.343: DBG: restart file buffer aging timer *Apr 24 13:03:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 13:03:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 13:03:41.343: DBG: current hash elem num 1 *Apr 24 13:03:41.343: DBG: restart file buffer aging timer *Apr 24 13:04:41.343: pp: file buffer manager 3D9E437C aging timer expired *Apr 24 13:04:41.343: DBG: file buffer aging timeout, buffer total_size(1839), file_num(1) *Apr 24 13:04:41.343: DBG: current hash elem num 0