cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
980
Views
0
Helpful
8
Replies

7942 registration

aguy
Beginner
Beginner

Hi guys.  Im testing a new network over a vpn and ran into an issue with my 7942.  The phone has access to CM with ports open for services like tfp and sccp.  It downloads the image and boots but then has an x next to the logo.

Screen Shot 2013-09-03 at 1.39.22 PM.png

Im guessing becasue it cant register properly.  Then the phone comes up with the correct extension and says its registered in CM.  When I try to place a call it immedialy goes to SRST.  Im able to make calls then.  Anyone have some insight on how to fix my registration issue?

8 Replies 8

Anas Abueideh
Engager
Engager

Hi,

can you check the delay of your vpn connection.

HTH

Anas

please rate all the helpful posts

The vpn is fine.  Low latency 60-70 ms.  Here is the tftp sdi log if anyone can help me out.  The phone is 10.5.100.22.

12:34:27.227 |RequestPoller::pollForRequest(30), fd(63) Read Ready
12:34:27.228 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa190968], socket(63)
12:34:27.228 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa190968], socket(63) accept() success, Socket(17), RemoteIP(10.5.100.22), Port(50872)
12:34:27.228 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa190968], socket(63)
12:34:27.228 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa190968)
12:34:27.228 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa190968])
12:34:27.228 |CReqContext::CReqContext(0xa191348)
12:34:27.228 |-->TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa190968~10~10.5.100.22~50872]
12:34:27.228 |-->TID[a2fd0b90] HTTPEngine::getRequest(), [0xa190968~10~10.5.100.22~50872]
12:34:27.234 |pollFor(1), Time(5), poll(17) Read Ready
12:34:27.234 |TID[a2fd0b90] HTTPEngine::readRequest(), [0xa190968~10~10.5.100.22~50872], socket(17), received size[66]
12:34:27.234 |   TID[a2fd0b90] HTTPEngine::getRequest(), [0xa190968~10~10.5.100.22~50872] socket(17), ReqTimeout[0], Request[GET /CTLSEPD0C282D0A42A.tlv HTTP/1.1
Host:10.0.100.10:2125620]
12:34:27.234 |   TID[a2fd0b90] HTTPEngine::getRequest(), [0xa190968~10~10.5.100.22~50872] File Requested CTLSEPD0C282D0A42A.tlv
12:34:27.234 |<--TID[a2fd0b90] HTTPEngine::getRequest(), [0xa190968~10~10.5.100.22~50872]
12:34:27.234 |-->CReqContext::tftp[0xa190968~10~10.5.100.22~50872]
12:34:27.234 |CReqContext::CheckAndSetIsStatic(ctlsepd0c282d0a42a.tlv) is (Not a Static) File
12:34:27.234 |CReqContext::isCTLCAPFRequest[CTLSEPD0C282D0A42A.tlv] Not a CTLCAPF File
12:34:27.234 |   CReqContext::tftp[0xa190968~10~10.5.100.22~50872] HandleCTL: [CTLSEPD0C282D0A42A.tlv] is CTLSEPMac.tlv file, Searching[D0C282D0A42A]
12:34:27.235 |TFTPCache::FindMatching(D0C282D0A42A), Found[SEPD0C282D0A42A.cnf.xml.sgn]
12:34:27.235 |   CReqContext::tftp[0xa190968~10~10.5.100.22~50872] HandleCTL: Match[D0C282D0A42A] Found, looking for [CTLFile.tlv] to serve
12:34:27.235 |CReqContext::FindAndServe SignFileFlag[0]
12:34:27.235 |-->HTTPEngine::sendNak[0xa190968~10~10.5.100.22~50872][1]
12:34:27.235 |   HTTPEngine::sendNak[0xa190968~10~10.5.100.22~50872][1] FileName[CTLSEPD0C282D0A42A.tlv], Version[HTTP/1.1]
12:34:27.235 |   HTTPEngine::sendNak[0xa190968~10~10.5.100.22~50872][1] [64][HTTP/1.1 404 Not Found
Content-length: 0
Content-type: */*

]
12:34:27.235 |-->HTTPEngine::sendMessage[0xa190968~10~10.5.100.22~50872]
12:34:27.235 |   HTTPEngine::sendMessage[0xa190968~10~10.5.100.22~50872] Socket[17], Length[64]
12:34:27.235 |   HTTPEngine::sendMessage[0xa190968~10~10.5.100.22~50872] Send Complete, Size[64], Sent[64]
12:34:27.235 |<--HTTPEngine::sendMessage[0xa190968~10~10.5.100.22~50872]
12:34:27.235 |<--HTTPEngine::sendNak[0xa190968~10~10.5.100.22~50872][1]
12:34:27.235 |<--CReqContext::tftp[0xa190968~10~10.5.100.22~50872]
12:34:27.235 |<--TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa190968~10~10.5.100.22~50872]
12:34:27.235 |TID[a2fd0b90] CReqContext::~CReqContext[0xa190968~10~10.5.100.22~50872]
12:34:27.462 |RequestPoller::pollForRequest(30), fd(63) Read Ready
12:34:27.462 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa191348], socket(63)
12:34:27.462 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa191348], socket(63) accept() success, Socket(17), RemoteIP(10.5.100.22), Port(51355)
12:34:27.462 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa191348], socket(63)
12:34:27.462 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa191348)
12:34:27.462 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa191348])
12:34:27.462 |CReqContext::CReqContext(0xa191d28)
12:34:27.462 |-->TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa191348~11~10.5.100.22~51355]
12:34:27.462 |-->TID[a43d0b90] HTTPEngine::getRequest(), [0xa191348~11~10.5.100.22~51355]
12:34:27.468 |pollFor(1), Time(5), poll(17) Read Ready
12:34:27.468 |TID[a43d0b90] HTTPEngine::readRequest(), [0xa191348~11~10.5.100.22~51355], socket(17), received size[66]
12:34:27.468 |   TID[a43d0b90] HTTPEngine::getRequest(), [0xa191348~11~10.5.100.22~51355] socket(17), ReqTimeout[0], Request[GET /ITLSEPD0C282D0A42A.tlv HTTP/1.1
Host:10.0.100.10:2125620]
12:34:27.468 |   TID[a43d0b90] HTTPEngine::getRequest(), [0xa191348~11~10.5.100.22~51355] File Requested ITLSEPD0C282D0A42A.tlv
12:34:27.468 |<--TID[a43d0b90] HTTPEngine::getRequest(), [0xa191348~11~10.5.100.22~51355]
12:34:27.468 |-->CReqContext::tftp[0xa191348~11~10.5.100.22~51355]
12:34:27.468 |CReqContext::CheckAndSetIsStatic(itlsepd0c282d0a42a.tlv) is (Not a Static) File
12:34:27.468 |CReqContext::isCTLCAPFRequest[ITLSEPD0C282D0A42A.tlv] Not a CTLCAPF File
12:34:27.468 |   CReqContext::tftp[0xa191348~11~10.5.100.22~51355] HandleITL: [ITLSEPD0C282D0A42A.tlv] is ITLSEPMac.tlv file, Searching[D0C282D0A42A]
12:34:27.468 |TFTPCache::FindMatching(D0C282D0A42A), Found[SEPD0C282D0A42A.cnf.xml.sgn]
12:34:27.469 |   CReqContext::tftp[0xa191348~11~10.5.100.22~51355] HandleITL: Match[D0C282D0A42A] Found, looking for [ITLFile.tlv] to serve
12:34:27.469 |CReqContext::FindAndServe SignFileFlag[0]
12:34:27.469 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid
12:34:27.469 |CReqContext::FindAndServe(1)[0xa191348~11~10.5.100.22~51355],[(ITLFile.tlv),(2567),(0x9b0d108)] found in config cache
12:34:27.469 |-->CReqContext::serve[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |-->CSendBuffer::Init[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |<--CSendBuffer::Init[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |-->HTTPEngine::sendResponse[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |   HTTPEngine::sendResponse[0xa191348~11~10.5.100.22~51355] FileName[ITLSEPD0C282D0A42A.tlv], Version[HTTP/1.1], Size[2567]
12:34:27.469 |   HTTPEngine::sendResponse[0xa191348~11~10.5.100.22~51355] [85][HTTP/1.1 200 OK
Content-length: 2567
Cache-Control: no-store
Content-type: */*

]
12:34:27.469 |-->HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |   HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355] Socket[17], Length[85]
12:34:27.469 |   HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355] Send Complete, Size[85], Sent[85]
12:34:27.469 |<--HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |-->HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |   HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355] Socket[17], Length[2567]
12:34:27.469 |   HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355] Send Complete, Size[2567], Sent[2567]
12:34:27.469 |<--HTTPEngine::sendMessage[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |<--HTTPEngine::sendResponse[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |<--CReqContext::serve[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |<--CReqContext::tftp[0xa191348~11~10.5.100.22~51355]
12:34:27.469 |<--TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa191348~11~10.5.100.22~51355]
12:34:27.469 |TID[a43d0b90] CReqContext::~CReqContext[0xa191348~11~10.5.100.22~51355]
12:34:27.963 |RequestPoller::pollForRequest(30), fd(63) Read Ready
12:34:27.963 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa191d28], socket(63)
12:34:27.963 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa191d28], socket(63) accept() success, Socket(17), RemoteIP(10.5.100.22), Port(50522)
12:34:27.963 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa191d28], socket(63)
12:34:27.963 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa191d28)
12:34:27.963 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa191d28])
12:34:27.963 |CReqContext::CReqContext(0xa192708)
12:34:27.963 |-->TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa191d28~12~10.5.100.22~50522]
12:34:27.963 |-->TID[a39d0b90] HTTPEngine::getRequest(), [0xa191d28~12~10.5.100.22~50522]
12:34:27.969 |pollFor(1), Time(5), poll(17) Read Ready
12:34:27.969 |TID[a39d0b90] HTTPEngine::readRequest(), [0xa191d28~12~10.5.100.22~50522], socket(17), received size[71]
12:34:27.969 |   TID[a39d0b90] HTTPEngine::getRequest(), [0xa191d28~12~10.5.100.22~50522] socket(17), ReqTimeout[0], Request[GET /SEPD0C282D0A42A.cnf.xml.sgn HTTP/1.1
Host:10.0.100.10:2125620]
12:34:27.969 |   TID[a39d0b90] HTTPEngine::getRequest(), [0xa191d28~12~10.5.100.22~50522] File Requested SEPD0C282D0A42A.cnf.xml.sgn
12:34:27.969 |<--TID[a39d0b90] HTTPEngine::getRequest(), [0xa191d28~12~10.5.100.22~50522]
12:34:27.969 |-->CReqContext::tftp[0xa191d28~12~10.5.100.22~50522]
12:34:27.969 |CReqContext::CheckAndSetIsStatic(sepd0c282d0a42a.cnf.xml.sgn) is (Not a Static) File
12:34:27.969 |CReqContext::isCTLCAPFRequest[SEPD0C282D0A42A.cnf.xml.sgn] Not a CTLCAPF File
12:34:27.969 |CReqContext::FindAndServe SignFileFlag[0]
12:34:27.970 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid
12:34:27.970 |CReqContext::FindAndServe(1)[0xa191d28~12~10.5.100.22~50522],[(SEPD0C282D0A42A.cnf.xml.sgn),(7536),(0x9e8b410)] found in config cache
12:34:27.970 |-->CReqContext::serve[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |-->CSendBuffer::Init[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |<--CSendBuffer::Init[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |-->HTTPEngine::sendResponse[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |   HTTPEngine::sendResponse[0xa191d28~12~10.5.100.22~50522] FileName[SEPD0C282D0A42A.cnf.xml.sgn], Version[HTTP/1.1], Size[7536]
12:34:27.970 |   HTTPEngine::sendResponse[0xa191d28~12~10.5.100.22~50522] [85][HTTP/1.1 200 OK
Content-length: 7536
Cache-Control: no-store
Content-type: */*

]
12:34:27.970 |-->HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |   HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522] Socket[17], Length[85]
12:34:27.970 |   HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522] Send Complete, Size[85], Sent[85]
12:34:27.970 |<--HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |-->HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |   HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522] Socket[17], Length[7536]
12:34:27.970 |   HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522] Send Complete, Size[7536], Sent[7536]
12:34:27.970 |<--HTTPEngine::sendMessage[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |<--HTTPEngine::sendResponse[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |<--CReqContext::serve[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |<--CReqContext::tftp[0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |<--TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa191d28~12~10.5.100.22~50522]
12:34:27.970 |TID[a39d0b90] CReqContext::~CReqContext[0xa191d28~12~10.5.100.22~50522]
12:34:35.222 |   TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9968129]
12:34:36.757 |RequestPoller::pollForRequest(30), fd(63) Read Ready
12:34:36.757 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa192708], socket(63)
12:34:36.757 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa192708], socket(63) accept() success, Socket(17), RemoteIP(10.5.100.22), Port(52549)
12:34:36.757 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa192708], socket(63)
12:34:36.757 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa192708)
12:34:36.757 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa192708])
12:34:36.757 |CReqContext::CReqContext(0xa1930e8)
12:34:36.757 |-->TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa192708~13~10.5.100.22~52549]
12:34:36.757 |-->TID[a1bd0b90] HTTPEngine::getRequest(), [0xa192708~13~10.5.100.22~52549]
12:34:36.764 |pollFor(1), Time(5), poll(17) Read Ready
12:34:36.764 |TID[a1bd0b90] HTTPEngine::readRequest(), [0xa192708~13~10.5.100.22~52549], socket(17), received size[61]
12:34:36.764 |   TID[a1bd0b90] HTTPEngine::getRequest(), [0xa192708~13~10.5.100.22~52549] socket(17), ReqTimeout[0], Request[GET /tzupdater.jar.sgn HTTP/1.1
Host:10.0.100.10:2125620]
12:34:36.764 |   TID[a1bd0b90] HTTPEngine::getRequest(), [0xa192708~13~10.5.100.22~52549] File Requested tzupdater.jar.sgn
12:34:36.764 |<--TID[a1bd0b90] HTTPEngine::getRequest(), [0xa192708~13~10.5.100.22~52549]
12:34:36.764 |-->CReqContext::tftp[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |CReqContext::CheckAndSetIsStatic(tzupdater.jar.sgn) is (Static) File
12:34:36.764 |CReqContext::FindAndServe SignFileFlag[0]
12:34:36.764 |CReqContext::FindAndServe(1)[0xa192708~13~10.5.100.22~52549],[(tzupdater.jar.sgn),(253703),(0x8e12c40)] found in bin cache
12:34:36.764 |-->CReqContext::serve[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |-->CSendBuffer::Init[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |<--CSendBuffer::Init[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |-->HTTPEngine::sendResponse[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |   HTTPEngine::sendResponse[0xa192708~13~10.5.100.22~52549] FileName[tzupdater.jar.sgn], Version[HTTP/1.1], Size[253703]
12:34:36.764 |   HTTPEngine::sendResponse[0xa192708~13~10.5.100.22~52549] [62][HTTP/1.1 200 OK
Content-length: 253703
Content-type: */*

]
12:34:36.764 |-->HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |   HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549] Socket[17], Length[62]
12:34:36.764 |   HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549] Send Complete, Size[62], Sent[62]
12:34:36.764 |<--HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |-->HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549]
12:34:36.764 |   HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549] Socket[17], Length[253703]
12:34:40.166 |   HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549] Send Complete, Size[253703], Sent[253703]
12:34:40.166 |<--HTTPEngine::sendMessage[0xa192708~13~10.5.100.22~52549]
12:34:40.166 |<--HTTPEngine::sendResponse[0xa192708~13~10.5.100.22~52549]
12:34:40.166 |<--CReqContext::serve[0xa192708~13~10.5.100.22~52549]
12:34:40.166 |<--CReqContext::tftp[0xa192708~13~10.5.100.22~52549]
12:34:40.166 |<--TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa192708~13~10.5.100.22~52549]
12:34:40.166 |TID[a1bd0b90] CReqContext::~CReqContext[0xa192708~13~10.5.100.22~52549]

yahsiel2004
Rising star
Rising star

Have you tried opening all ports on the firewall to verify that this is not a firewall ACL issue?

HTH

Regards,

Yosh

HTH Regards, Yosh

Yep its wide open for testing.

Andre

Have you deleted the phone from CUCM and performed a factory reset?

HTH

Regards,

Yosh

HTH Regards, Yosh

Also removed the CTL.tlv file.

HTH

Regards,

Yosh

HTH Regards, Yosh

Ive been factory resetting with no luck and I tried deleting the phone like you said.  Deleting dint work.  Pulled a new phone out the box and tried that but same issue so dint have to touch the CTL.tlv.  Here is the log from the last attempt on 10.5.100.22:

14:25:24.523 |<--CServiceModule::BuildFiles()

14:25:24.523 |<--CServiceModule::HandleDeviceCNFinal

14:25:24.523 |<--CServiceModule::HandleChangeNotification

14:25:30.578 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:25:30.578 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cbd68], socket(63)

14:25:30.578 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cbd68], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(49380)

14:25:30.578 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cbd68], socket(63)

14:25:30.578 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1cbd68)

14:25:30.578 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1cbd68])

14:25:30.578 |CReqContext::CReqContext(0xa1cc748)

14:25:30.578 |-->TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1cbd68~106~10.5.100.22~49380]

14:25:30.578 |-->TID[a43d0b90] HTTPEngine::getRequest(), [0xa1cbd68~106~10.5.100.22~49380]

14:25:30.589 |pollFor(1), Time(5), poll(30) Read Ready

14:25:30.589 |TID[a43d0b90] HTTPEngine::readRequest(), [0xa1cbd68~106~10.5.100.22~49380], socket(30), received size[66]

14:25:30.589 |   TID[a43d0b90] HTTPEngine::getRequest(), [0xa1cbd68~106~10.5.100.22~49380] socket(30), ReqTimeout[0], Request[GET /CTLSEPD0C282D0A42A.tlv HTTP/1.1

Host:10.0.100.10:2125620]

14:25:30.589 |   TID[a43d0b90] HTTPEngine::getRequest(), [0xa1cbd68~106~10.5.100.22~49380] File Requested CTLSEPD0C282D0A42A.tlv

14:25:30.589 |<--TID[a43d0b90] HTTPEngine::getRequest(), [0xa1cbd68~106~10.5.100.22~49380]

14:25:30.589 |-->CReqContext::tftp[0xa1cbd68~106~10.5.100.22~49380]

14:25:30.589 |CReqContext::CheckAndSetIsStatic(ctlsepd0c282d0a42a.tlv) is (Not a Static) File

14:25:30.589 |CReqContext::isCTLCAPFRequest[CTLSEPD0C282D0A42A.tlv] Not a CTLCAPF File

14:25:30.589 |   CReqContext::tftp[0xa1cbd68~106~10.5.100.22~49380] HandleCTL: [CTLSEPD0C282D0A42A.tlv] is CTLSEPMac.tlv file, Searching[D0C282D0A42A]

14:25:30.589 |TFTPCache::FindMatching(D0C282D0A42A), Found[SEPD0C282D0A42A.cnf.xml.sgn]

14:25:30.589 |   CReqContext::tftp[0xa1cbd68~106~10.5.100.22~49380] HandleCTL: Match[D0C282D0A42A] Found, looking for [CTLFile.tlv] to serve

14:25:30.589 |CReqContext::FindAndServe SignFileFlag[0]

14:25:30.590 |-->HTTPEngine::sendNak[0xa1cbd68~106~10.5.100.22~49380][1]

14:25:30.590 |   HTTPEngine::sendNak[0xa1cbd68~106~10.5.100.22~49380][1] FileName[CTLSEPD0C282D0A42A.tlv], Version[HTTP/1.1]

14:25:30.590 |   HTTPEngine::sendNak[0xa1cbd68~106~10.5.100.22~49380][1] [64][HTTP/1.1 404 Not Found

Content-length: 0

Content-type: */*

]

14:25:30.590 |-->HTTPEngine::sendMessage[0xa1cbd68~106~10.5.100.22~49380]

14:25:30.590 |   HTTPEngine::sendMessage[0xa1cbd68~106~10.5.100.22~49380] Socket[30], Length[64]

14:25:30.590 |   HTTPEngine::sendMessage[0xa1cbd68~106~10.5.100.22~49380] Send Complete, Size[64], Sent[64]

14:25:30.590 |<--HTTPEngine::sendMessage[0xa1cbd68~106~10.5.100.22~49380]

14:25:30.590 |<--HTTPEngine::sendNak[0xa1cbd68~106~10.5.100.22~49380][1]

14:25:30.590 |<--CReqContext::tftp[0xa1cbd68~106~10.5.100.22~49380]

14:25:30.590 |<--TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1cbd68~106~10.5.100.22~49380]

14:25:30.590 |TID[a43d0b90] CReqContext::~CReqContext[0xa1cbd68~106~10.5.100.22~49380]

14:25:30.792 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:25:30.792 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cc748], socket(63)

14:25:30.792 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cc748], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(51233)

14:25:30.792 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cc748], socket(63)

14:25:30.792 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1cc748)

14:25:30.792 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1cc748])

14:25:30.792 |CReqContext::CReqContext(0xa1cd128)

14:25:30.792 |-->TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1cc748~107~10.5.100.22~51233]

14:25:30.792 |-->TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |pollFor(1), Time(5), poll(30) Read Ready

14:25:30.799 |TID[a39d0b90] HTTPEngine::readRequest(), [0xa1cc748~107~10.5.100.22~51233], socket(30), received size[66]

14:25:30.799 |   TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cc748~107~10.5.100.22~51233] socket(30), ReqTimeout[0], Request[GET /ITLSEPD0C282D0A42A.tlv HTTP/1.1

Host:10.0.100.10:2125620]

14:25:30.799 |   TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cc748~107~10.5.100.22~51233] File Requested ITLSEPD0C282D0A42A.tlv

14:25:30.799 |<--TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |-->CReqContext::tftp[0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |CReqContext::CheckAndSetIsStatic(itlsepd0c282d0a42a.tlv) is (Not a Static) File

14:25:30.799 |CReqContext::isCTLCAPFRequest[ITLSEPD0C282D0A42A.tlv] Not a CTLCAPF File

14:25:30.799 |   CReqContext::tftp[0xa1cc748~107~10.5.100.22~51233] HandleITL: [ITLSEPD0C282D0A42A.tlv] is ITLSEPMac.tlv file, Searching[D0C282D0A42A]

14:25:30.799 |TFTPCache::FindMatching(D0C282D0A42A), Found[SEPD0C282D0A42A.cnf.xml.sgn]

14:25:30.799 |   CReqContext::tftp[0xa1cc748~107~10.5.100.22~51233] HandleITL: Match[D0C282D0A42A] Found, looking for [ITLFile.tlv] to serve

14:25:30.799 |CReqContext::FindAndServe SignFileFlag[0]

14:25:30.799 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

14:25:30.799 |CReqContext::FindAndServe(1)[0xa1cc748~107~10.5.100.22~51233],[(ITLFile.tlv),(2567),(0x9b0d108)] found in config cache

14:25:30.799 |-->CReqContext::serve[0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |-->CSendBuffer::Init[0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |<--CSendBuffer::Init[0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |-->HTTPEngine::sendResponse[0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |   HTTPEngine::sendResponse[0xa1cc748~107~10.5.100.22~51233] FileName[ITLSEPD0C282D0A42A.tlv], Version[HTTP/1.1], Size[2567]

14:25:30.799 |   HTTPEngine::sendResponse[0xa1cc748~107~10.5.100.22~51233] [85][HTTP/1.1 200 OK

Content-length: 2567

Cache-Control: no-store

Content-type: */*

]

14:25:30.799 |-->HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233]

14:25:30.799 |   HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233] Socket[30], Length[85]

14:25:30.800 |   HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233] Send Complete, Size[85], Sent[85]

14:25:30.800 |<--HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |-->HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |   HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233] Socket[30], Length[2567]

14:25:30.800 |   HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233] Send Complete, Size[2567], Sent[2567]

14:25:30.800 |<--HTTPEngine::sendMessage[0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |<--HTTPEngine::sendResponse[0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |<--CReqContext::serve[0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |<--CReqContext::tftp[0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |<--TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1cc748~107~10.5.100.22~51233]

14:25:30.800 |TID[a39d0b90] CReqContext::~CReqContext[0xa1cc748~107~10.5.100.22~51233]

14:25:31.205 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:25:31.205 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cd128], socket(63)

14:25:31.205 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cd128], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(52561)

14:25:31.205 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cd128], socket(63)

14:25:31.205 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1cd128)

14:25:31.205 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1cd128])

14:25:31.205 |CReqContext::CReqContext(0xa1cdb08)

14:25:31.206 |-->TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1cd128~108~10.5.100.22~52561]

14:25:31.206 |-->TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1cd128~108~10.5.100.22~52561]

14:25:31.212 |pollFor(1), Time(5), poll(30) Read Ready

14:25:31.212 |TID[a1bd0b90] HTTPEngine::readRequest(), [0xa1cd128~108~10.5.100.22~52561], socket(30), received size[71]

14:25:31.212 |   TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1cd128~108~10.5.100.22~52561] socket(30), ReqTimeout[0], Request[GET /SEPD0C282D0A42A.cnf.xml.sgn HTTP/1.1

Host:10.0.100.10:2125620]

14:25:31.212 |   TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1cd128~108~10.5.100.22~52561] File Requested SEPD0C282D0A42A.cnf.xml.sgn

14:25:31.212 |<--TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1cd128~108~10.5.100.22~52561]

14:25:31.212 |-->CReqContext::tftp[0xa1cd128~108~10.5.100.22~52561]

14:25:31.212 |CReqContext::CheckAndSetIsStatic(sepd0c282d0a42a.cnf.xml.sgn) is (Not a Static) File

14:25:31.212 |CReqContext::isCTLCAPFRequest[SEPD0C282D0A42A.cnf.xml.sgn] Not a CTLCAPF File

14:25:31.212 |CReqContext::FindAndServe SignFileFlag[0]

14:25:31.212 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

14:25:31.212 |CReqContext::FindAndServe(1)[0xa1cd128~108~10.5.100.22~52561],[(SEPD0C282D0A42A.cnf.xml.sgn),(6425),(0x9ce1560)] found in config cache

14:25:31.212 |-->CReqContext::serve[0xa1cd128~108~10.5.100.22~52561]

14:25:31.212 |-->CSendBuffer::Init[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |<--CSendBuffer::Init[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |-->HTTPEngine::sendResponse[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |   HTTPEngine::sendResponse[0xa1cd128~108~10.5.100.22~52561] FileName[SEPD0C282D0A42A.cnf.xml.sgn], Version[HTTP/1.1], Size[6425]

14:25:31.213 |   HTTPEngine::sendResponse[0xa1cd128~108~10.5.100.22~52561] [85][HTTP/1.1 200 OK

Content-length: 6425

Cache-Control: no-store

Content-type: */*

]

14:25:31.213 |-->HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |   HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561] Socket[30], Length[85]

14:25:31.213 |   HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561] Send Complete, Size[85], Sent[85]

14:25:31.213 |<--HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |-->HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |   HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561] Socket[30], Length[6425]

14:25:31.213 |   HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561] Send Complete, Size[6425], Sent[6425]

14:25:31.213 |<--HTTPEngine::sendMessage[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |<--HTTPEngine::sendResponse[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |<--CReqContext::serve[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |<--CReqContext::tftp[0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |<--TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1cd128~108~10.5.100.22~52561]

14:25:31.213 |TID[a1bd0b90] CReqContext::~CReqContext[0xa1cd128~108~10.5.100.22~52561]

14:25:32.814 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:25:32.814 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cdb08], socket(63)

14:25:32.814 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cdb08], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(53183)

14:25:32.814 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cdb08], socket(63)

14:25:32.814 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1cdb08)

14:25:32.814 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1cdb08])

14:25:32.814 |CReqContext::CReqContext(0xa1ce4e8)

14:25:32.814 |-->TID[a25d0b90] CReqContext::HTTPProxyRun(), [0xa1cdb08~109~10.5.100.22~53183]

14:25:32.814 |-->TID[a25d0b90] HTTPEngine::getRequest(), [0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |pollFor(1), Time(5), poll(30) Read Ready

14:25:32.846 |TID[a25d0b90] HTTPEngine::readRequest(), [0xa1cdb08~109~10.5.100.22~53183], socket(30), received size[61]

14:25:32.846 |   TID[a25d0b90] HTTPEngine::getRequest(), [0xa1cdb08~109~10.5.100.22~53183] socket(30), ReqTimeout[0], Request[GET /tzupdater.jar.sgn HTTP/1.1

Host:10.0.100.10:2125620]

14:25:32.846 |   TID[a25d0b90] HTTPEngine::getRequest(), [0xa1cdb08~109~10.5.100.22~53183] File Requested tzupdater.jar.sgn

14:25:32.846 |<--TID[a25d0b90] HTTPEngine::getRequest(), [0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |-->CReqContext::tftp[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |CReqContext::CheckAndSetIsStatic(tzupdater.jar.sgn) is (Static) File

14:25:32.846 |CReqContext::FindAndServe SignFileFlag[0]

14:25:32.846 |CReqContext::FindAndServe(1)[0xa1cdb08~109~10.5.100.22~53183],[(tzupdater.jar.sgn),(253703),(0x8e12c40)] found in bin cache

14:25:32.846 |-->CReqContext::serve[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |-->CSendBuffer::Init[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |<--CSendBuffer::Init[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |-->HTTPEngine::sendResponse[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |   HTTPEngine::sendResponse[0xa1cdb08~109~10.5.100.22~53183] FileName[tzupdater.jar.sgn], Version[HTTP/1.1], Size[253703]

14:25:32.846 |   HTTPEngine::sendResponse[0xa1cdb08~109~10.5.100.22~53183] [62][HTTP/1.1 200 OK

Content-length: 253703

Content-type: */*

]

14:25:32.846 |-->HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |   HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183] Socket[30], Length[62]

14:25:32.846 |   HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183] Send Complete, Size[62], Sent[62]

14:25:32.846 |<--HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |-->HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183]

14:25:32.846 |   HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183] Socket[30], Length[253703]

14:25:33.323 |   TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9974759]

14:25:38.363 |   HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183] Send Complete, Size[253703], Sent[253703]

14:25:38.363 |<--HTTPEngine::sendMessage[0xa1cdb08~109~10.5.100.22~53183]

14:25:38.363 |<--HTTPEngine::sendResponse[0xa1cdb08~109~10.5.100.22~53183]

14:25:38.364 |<--CReqContext::serve[0xa1cdb08~109~10.5.100.22~53183]

14:25:38.364 |<--CReqContext::tftp[0xa1cdb08~109~10.5.100.22~53183]

14:25:38.364 |<--TID[a25d0b90] CReqContext::HTTPProxyRun(), [0xa1cdb08~109~10.5.100.22~53183]

14:25:38.364 |TID[a25d0b90] CReqContext::~CReqContext[0xa1cdb08~109~10.5.100.22~53183]

14:25:45.778 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:25:45.778 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1ce4e8], socket(63)

14:25:45.778 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1ce4e8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(51069)

14:25:45.778 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1ce4e8], socket(63)

14:25:45.778 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1ce4e8)

14:25:45.778 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1ce4e8])

14:25:45.778 |CReqContext::CReqContext(0xa1ceec8)

14:25:45.778 |-->TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.778 |-->TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |pollFor(1), Time(5), poll(30) Read Ready

14:25:45.785 |TID[a2fd0b90] HTTPEngine::readRequest(), [0xa1ce4e8~110~10.5.100.22~51069], socket(30), received size[81]

14:25:45.785 |   TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1ce4e8~110~10.5.100.22~51069] socket(30), ReqTimeout[0], Request[GET /English_United_States/mk-sccp.jar.sgn HTTP/1.1

Host:10.0.100.10:2125620]

14:25:45.785 |   TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1ce4e8~110~10.5.100.22~51069] File Requested English_United_States/mk-sccp.jar.sgn

14:25:45.785 |<--TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |-->CReqContext::tftp[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |CReqContext::CheckAndSetIsStatic(english_united_states/mk-sccp.jar.sgn) is (Static) File

14:25:45.785 |CReqContext::FindAndServe SignFileFlag[0]

14:25:45.785 |CReqContext::FindAndServe(1)[0xa1ce4e8~110~10.5.100.22~51069],[(English_United_States/mk-sccp.jar.sgn),(58822),(0xa2e5ca8)] found in bin cache

14:25:45.785 |-->CReqContext::serve[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |-->CSendBuffer::Init[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |<--CSendBuffer::Init[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |-->HTTPEngine::sendResponse[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |   HTTPEngine::sendResponse[0xa1ce4e8~110~10.5.100.22~51069] FileName[English_United_States/mk-sccp.jar.sgn], Version[HTTP/1.1], Size[58822]

14:25:45.785 |   HTTPEngine::sendResponse[0xa1ce4e8~110~10.5.100.22~51069] [61][HTTP/1.1 200 OK

Content-length: 58822

Content-type: */*

]

14:25:45.785 |-->HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |   HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069] Socket[30], Length[61]

14:25:45.785 |   HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069] Send Complete, Size[61], Sent[61]

14:25:45.785 |<--HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |-->HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069]

14:25:45.785 |   HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069] Socket[30], Length[58822]

14:25:46.252 |   HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069] Send Complete, Size[58822], Sent[58822]

14:25:46.252 |<--HTTPEngine::sendMessage[0xa1ce4e8~110~10.5.100.22~51069]

14:25:46.252 |<--HTTPEngine::sendResponse[0xa1ce4e8~110~10.5.100.22~51069]

14:25:46.252 |<--CReqContext::serve[0xa1ce4e8~110~10.5.100.22~51069]

14:25:46.252 |<--CReqContext::tftp[0xa1ce4e8~110~10.5.100.22~51069]

14:25:46.252 |<--TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa1ce4e8~110~10.5.100.22~51069]

14:25:46.252 |TID[a2fd0b90] CReqContext::~CReqContext[0xa1ce4e8~110~10.5.100.22~51069]

14:25:47.058 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:25:47.058 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1ceec8], socket(63)

14:25:47.058 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1ceec8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(52033)

14:25:47.058 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1ceec8], socket(63)

14:25:47.058 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1ceec8)

14:25:47.058 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1ceec8])

14:25:47.058 |CReqContext::CReqContext(0xa1cf8a8)

14:25:47.058 |-->TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1ceec8~111~10.5.100.22~52033]

14:25:47.058 |-->TID[a43d0b90] HTTPEngine::getRequest(), [0xa1ceec8~111~10.5.100.22~52033]

14:25:47.067 |pollFor(1), Time(5), poll(30) Read Ready

14:25:47.067 |TID[a43d0b90] HTTPEngine::readRequest(), [0xa1ceec8~111~10.5.100.22~52033], socket(30), received size[74]

14:25:47.067 |   TID[a43d0b90] HTTPEngine::getRequest(), [0xa1ceec8~111~10.5.100.22~52033] socket(30), ReqTimeout[0], Request[GET /United_States/g3-tones.xml.sgn HTTP/1.1

Host:10.0.100.10:2125620]

14:25:47.067 |   TID[a43d0b90] HTTPEngine::getRequest(), [0xa1ceec8~111~10.5.100.22~52033] File Requested United_States/g3-tones.xml.sgn

14:25:47.067 |<--TID[a43d0b90] HTTPEngine::getRequest(), [0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |-->CReqContext::tftp[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |CReqContext::CheckAndSetIsStatic(united_states/g3-tones.xml.sgn) is (Static) File

14:25:47.068 |CReqContext::FindAndServe SignFileFlag[0]

14:25:47.068 |CReqContext::FindAndServe(1)[0xa1ceec8~111~10.5.100.22~52033],[(United_States/g3-tones.xml.sgn),(2133),(0xa2f4768)] found in bin cache

14:25:47.068 |-->CReqContext::serve[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |-->CSendBuffer::Init[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |<--CSendBuffer::Init[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |-->HTTPEngine::sendResponse[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |   HTTPEngine::sendResponse[0xa1ceec8~111~10.5.100.22~52033] FileName[United_States/g3-tones.xml.sgn], Version[HTTP/1.1], Size[2133]

14:25:47.068 |   HTTPEngine::sendResponse[0xa1ceec8~111~10.5.100.22~52033] [60][HTTP/1.1 200 OK

Content-length: 2133

Content-type: */*

]

14:25:47.068 |-->HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |   HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033] Socket[30], Length[60]

14:25:47.068 |   HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033] Send Complete, Size[60], Sent[60]

14:25:47.068 |<--HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |-->HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |   HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033] Socket[30], Length[2133]

14:25:47.068 |   HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033] Send Complete, Size[2133], Sent[2133]

14:25:47.068 |<--HTTPEngine::sendMessage[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |<--HTTPEngine::sendResponse[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |<--CReqContext::serve[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |<--CReqContext::tftp[0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |<--TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1ceec8~111~10.5.100.22~52033]

14:25:47.068 |TID[a43d0b90] CReqContext::~CReqContext[0xa1ceec8~111~10.5.100.22~52033]

14:26:03.457 |   TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9974789]

14:26:33.587 |   TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9974819]

14:26:53.435 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:26:53.435 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cf8a8], socket(63)

14:26:53.435 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cf8a8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(52970)

14:26:53.435 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1cf8a8], socket(63)

14:26:53.435 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1cf8a8)

14:26:53.435 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1cf8a8])

14:26:53.435 |-->TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.435 |-->TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.435 |CReqContext::CReqContext(0xa1d0288)

14:26:53.441 |pollFor(1), Time(5), poll(30) Read Ready

14:26:53.441 |TID[a39d0b90] HTTPEngine::readRequest(), [0xa1cf8a8~112~10.5.100.22~52970], socket(30), received size[66]

14:26:53.441 |   TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cf8a8~112~10.5.100.22~52970] socket(30), ReqTimeout[0], Request[GET /CTLSEPD0C282D0A42A.tlv HTTP/1.1

Host:10.0.100.10:2125620]

14:26:53.441 |   TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cf8a8~112~10.5.100.22~52970] File Requested CTLSEPD0C282D0A42A.tlv

14:26:53.441 |<--TID[a39d0b90] HTTPEngine::getRequest(), [0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.441 |-->CReqContext::tftp[0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.441 |CReqContext::CheckAndSetIsStatic(ctlsepd0c282d0a42a.tlv) is (Not a Static) File

14:26:53.441 |CReqContext::isCTLCAPFRequest[CTLSEPD0C282D0A42A.tlv] Not a CTLCAPF File

14:26:53.441 |   CReqContext::tftp[0xa1cf8a8~112~10.5.100.22~52970] HandleCTL: [CTLSEPD0C282D0A42A.tlv] is CTLSEPMac.tlv file, Searching[D0C282D0A42A]

14:26:53.442 |TFTPCache::FindMatching(D0C282D0A42A), Found[SEPD0C282D0A42A.cnf.xml.sgn]

14:26:53.442 |   CReqContext::tftp[0xa1cf8a8~112~10.5.100.22~52970] HandleCTL: Match[D0C282D0A42A] Found, looking for [CTLFile.tlv] to serve

14:26:53.442 |CReqContext::FindAndServe SignFileFlag[0]

14:26:53.442 |-->HTTPEngine::sendNak[0xa1cf8a8~112~10.5.100.22~52970][1]

14:26:53.442 |   HTTPEngine::sendNak[0xa1cf8a8~112~10.5.100.22~52970][1] FileName[CTLSEPD0C282D0A42A.tlv], Version[HTTP/1.1]

14:26:53.442 |   HTTPEngine::sendNak[0xa1cf8a8~112~10.5.100.22~52970][1] [64][HTTP/1.1 404 Not Found

Content-length: 0

Content-type: */*

]

14:26:53.442 |-->HTTPEngine::sendMessage[0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.442 |   HTTPEngine::sendMessage[0xa1cf8a8~112~10.5.100.22~52970] Socket[30], Length[64]

14:26:53.442 |   HTTPEngine::sendMessage[0xa1cf8a8~112~10.5.100.22~52970] Send Complete, Size[64], Sent[64]

14:26:53.442 |<--HTTPEngine::sendMessage[0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.442 |<--HTTPEngine::sendNak[0xa1cf8a8~112~10.5.100.22~52970][1]

14:26:53.442 |<--CReqContext::tftp[0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.442 |<--TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.442 |TID[a39d0b90] CReqContext::~CReqContext[0xa1cf8a8~112~10.5.100.22~52970]

14:26:53.668 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:26:53.668 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d0288], socket(63)

14:26:53.668 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d0288], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(53035)

14:26:53.668 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d0288], socket(63)

14:26:53.668 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d0288)

14:26:53.668 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d0288])

14:26:53.668 |CReqContext::CReqContext(0xa1d0c68)

14:26:53.668 |-->TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1d0288~113~10.5.100.22~53035]

14:26:53.668 |-->TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |pollFor(1), Time(5), poll(30) Read Ready

14:26:53.675 |TID[a1bd0b90] HTTPEngine::readRequest(), [0xa1d0288~113~10.5.100.22~53035], socket(30), received size[66]

14:26:53.675 |   TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d0288~113~10.5.100.22~53035] socket(30), ReqTimeout[0], Request[GET /ITLSEPD0C282D0A42A.tlv HTTP/1.1

Host:10.0.100.10:2125620]

14:26:53.675 |   TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d0288~113~10.5.100.22~53035] File Requested ITLSEPD0C282D0A42A.tlv

14:26:53.675 |<--TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |-->CReqContext::tftp[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |CReqContext::CheckAndSetIsStatic(itlsepd0c282d0a42a.tlv) is (Not a Static) File

14:26:53.675 |CReqContext::isCTLCAPFRequest[ITLSEPD0C282D0A42A.tlv] Not a CTLCAPF File

14:26:53.675 |   CReqContext::tftp[0xa1d0288~113~10.5.100.22~53035] HandleITL: [ITLSEPD0C282D0A42A.tlv] is ITLSEPMac.tlv file, Searching[D0C282D0A42A]

14:26:53.675 |TFTPCache::FindMatching(D0C282D0A42A), Found[SEPD0C282D0A42A.cnf.xml.sgn]

14:26:53.675 |   CReqContext::tftp[0xa1d0288~113~10.5.100.22~53035] HandleITL: Match[D0C282D0A42A] Found, looking for [ITLFile.tlv] to serve

14:26:53.675 |CReqContext::FindAndServe SignFileFlag[0]

14:26:53.675 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

14:26:53.675 |CReqContext::FindAndServe(1)[0xa1d0288~113~10.5.100.22~53035],[(ITLFile.tlv),(2567),(0x9b0d108)] found in config cache

14:26:53.675 |-->CReqContext::serve[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |-->CSendBuffer::Init[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |<--CSendBuffer::Init[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |-->HTTPEngine::sendResponse[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |   HTTPEngine::sendResponse[0xa1d0288~113~10.5.100.22~53035] FileName[ITLSEPD0C282D0A42A.tlv], Version[HTTP/1.1], Size[2567]

14:26:53.675 |   HTTPEngine::sendResponse[0xa1d0288~113~10.5.100.22~53035] [85][HTTP/1.1 200 OK

Content-length: 2567

Cache-Control: no-store

Content-type: */*

]

14:26:53.675 |-->HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |   HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035] Socket[30], Length[85]

14:26:53.675 |   HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035] Send Complete, Size[85], Sent[85]

14:26:53.675 |<--HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |-->HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |   HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035] Socket[30], Length[2567]

14:26:53.675 |   HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035] Send Complete, Size[2567], Sent[2567]

14:26:53.675 |<--HTTPEngine::sendMessage[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |<--HTTPEngine::sendResponse[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |<--CReqContext::serve[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |<--CReqContext::tftp[0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |<--TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1d0288~113~10.5.100.22~53035]

14:26:53.675 |TID[a1bd0b90] CReqContext::~CReqContext[0xa1d0288~113~10.5.100.22~53035]

14:26:54.168 |RequestPoller::pollForRequest(30), fd(63) Read Ready

14:26:54.168 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d0c68], socket(63)

14:26:54.168 |   TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d0c68], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.22), Port(50013)

14:26:54.168 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d0c68], socket(63)

14:26:54.168 |   TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d0c68)

14:26:54.168 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d0c68])

14:26:54.168 |CReqContext::CReqContext(0xa1d1648)

14:26:54.168 |-->TID[a25d0b90] CReqContext::HTTPProxyRun(), [0xa1d0c68~114~10.5.100.22~50013]

14:26:54.168 |-->TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |pollFor(1), Time(5), poll(30) Read Ready

14:26:54.175 |TID[a25d0b90] HTTPEngine::readRequest(), [0xa1d0c68~114~10.5.100.22~50013], socket(30), received size[71]

14:26:54.175 |   TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d0c68~114~10.5.100.22~50013] socket(30), ReqTimeout[0], Request[GET /SEPD0C282D0A42A.cnf.xml.sgn HTTP/1.1

Host:10.0.100.10:2125620]

14:26:54.175 |   TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d0c68~114~10.5.100.22~50013] File Requested SEPD0C282D0A42A.cnf.xml.sgn

14:26:54.175 |<--TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |-->CReqContext::tftp[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |CReqContext::CheckAndSetIsStatic(sepd0c282d0a42a.cnf.xml.sgn) is (Not a Static) File

14:26:54.175 |CReqContext::isCTLCAPFRequest[SEPD0C282D0A42A.cnf.xml.sgn] Not a CTLCAPF File

14:26:54.175 |CReqContext::FindAndServe SignFileFlag[0]

14:26:54.175 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

14:26:54.175 |CReqContext::FindAndServe(1)[0xa1d0c68~114~10.5.100.22~50013],[(SEPD0C282D0A42A.cnf.xml.sgn),(6425),(0x9ce1560)] found in config cache

14:26:54.175 |-->CReqContext::serve[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |-->CSendBuffer::Init[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |<--CSendBuffer::Init[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |-->HTTPEngine::sendResponse[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |   HTTPEngine::sendResponse[0xa1d0c68~114~10.5.100.22~50013] FileName[SEPD0C282D0A42A.cnf.xml.sgn], Version[HTTP/1.1], Size[6425]

14:26:54.175 |   HTTPEngine::sendResponse[0xa1d0c68~114~10.5.100.22~50013] [85][HTTP/1.1 200 OK

Content-length: 6425

Cache-Control: no-store

Content-type: */*

]

14:26:54.175 |-->HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |   HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013] Socket[30], Length[85]

14:26:54.175 |   HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013] Send Complete, Size[85], Sent[85]

14:26:54.175 |<--HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |-->HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |   HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013] Socket[30], Length[6425]

14:26:54.175 |   HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013] Send Complete, Size[6425], Sent[6425]

14:26:54.175 |<--HTTPEngine::sendMessage[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |<--HTTPEngine::sendResponse[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |<--CReqContext::serve[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |<--CReqContext::tftp[0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |<--TID[a25d0b90] CReqContext::HTTPProxyRun(), [0xa1d0c68~114~10.5.100.22~50013]

14:26:54.175 |TID[a25d0b90] CReqContext::~CReqContext[0xa1d0c68~114~10.5.100.22~50013]