7942 registration

aguy · ‎09-03-2013

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.

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?

Anas Abueideh · ‎09-14-2013

Hi,

can you check the delay of your vpn connection.

HTH

Anas

please rate all the helpful posts

aguy · ‎09-24-2013

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 · ‎09-24-2013

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

aguy · ‎09-24-2013

Yep its wide open for testing.

Andre

yahsiel2004 · ‎09-24-2013

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

HTH

Regards,

Yosh

HTH Regards, Yosh

yahsiel2004 · ‎09-24-2013

Also removed the CTL.tlv file.

HTH

Regards,

Yosh

HTH Regards, Yosh

aguy · ‎09-24-2013

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]

aguy · ‎09-24-2013

Here is the tftp log from the new out of box phone with a blank config

15:10:57.591 |<--CServiceModule::BuildFiles()

15:10:57.591 |<--CServiceModule::HandleDeviceCNFinal

15:10:57.591 |<--CServiceModule::HandleChangeNotification

15:11:03.647 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:11:03.647 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d47a8], socket(63)

15:11:03.647 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d47a8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(50876)

15:11:03.647 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d47a8], socket(63)

15:11:03.647 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d47a8)

15:11:03.647 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d47a8])

15:11:03.647 |-->TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa1d47a8~120~10.5.100.254~50876]

15:11:03.647 |-->TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d47a8~120~10.5.100.254~50876]

15:11:03.647 |CReqContext::CReqContext(0xa1d5188)

15:11:03.654 |pollFor(1), Time(5), poll(30) Read Ready

15:11:03.654 |TID[a2fd0b90] HTTPEngine::readRequest(), [0xa1d47a8~120~10.5.100.254~50876], socket(30), received size[66]

15:11:03.654 | TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d47a8~120~10.5.100.254~50876] socket(30), ReqTimeout[0], Request[GET /CTLSEP001D70FD338B.tlv HTTP/1.1

Host:10.0.100.10:2125620]

15:11:03.654 | TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d47a8~120~10.5.100.254~50876] File Requested CTLSEP001D70FD338B.tlv

15:11:03.654 |<--TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d47a8~120~10.5.100.254~50876]

15:11:03.654 |-->CReqContext::tftp[0xa1d47a8~120~10.5.100.254~50876]

15:11:03.654 |CReqContext::CheckAndSetIsStatic(ctlsep001d70fd338b.tlv) is (Not a Static) File

15:11:03.654 |CReqContext::isCTLCAPFRequest[CTLSEP001D70FD338B.tlv] Not a CTLCAPF File

15:11:03.654 | CReqContext::tftp[0xa1d47a8~120~10.5.100.254~50876] HandleCTL: [CTLSEP001D70FD338B.tlv] is CTLSEPMac.tlv file, Searching[001D70FD338B]

15:11:03.655 |TFTPCache::FindMatching(001D70FD338B), Found[SEP001D70FD338B.cnf.xml.sgn]

15:11:03.655 | CReqContext::tftp[0xa1d47a8~120~10.5.100.254~50876] HandleCTL: Match[001D70FD338B] Found, looking for [CTLFile.tlv] to serve

15:11:03.655 |CReqContext::FindAndServe SignFileFlag[0]

15:11:03.655 |-->HTTPEngine::sendNak[0xa1d47a8~120~10.5.100.254~50876][1]

15:11:03.655 | HTTPEngine::sendNak[0xa1d47a8~120~10.5.100.254~50876][1] FileName[CTLSEP001D70FD338B.tlv], Version[HTTP/1.1]

15:11:03.655 | HTTPEngine::sendNak[0xa1d47a8~120~10.5.100.254~50876][1] [64][HTTP/1.1 404 Not Found

Content-length: 0

Content-type: */*

]

15:11:03.655 |-->HTTPEngine::sendMessage[0xa1d47a8~120~10.5.100.254~50876]

15:11:03.655 | HTTPEngine::sendMessage[0xa1d47a8~120~10.5.100.254~50876] Socket[30], Length[64]

15:11:03.655 | HTTPEngine::sendMessage[0xa1d47a8~120~10.5.100.254~50876] Send Complete, Size[64], Sent[64]

15:11:03.655 |<--HTTPEngine::sendMessage[0xa1d47a8~120~10.5.100.254~50876]

15:11:03.655 |<--HTTPEngine::sendNak[0xa1d47a8~120~10.5.100.254~50876][1]

15:11:03.655 |<--CReqContext::tftp[0xa1d47a8~120~10.5.100.254~50876]

15:11:03.655 |<--TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa1d47a8~120~10.5.100.254~50876]

15:11:03.655 |TID[a2fd0b90] CReqContext::~CReqContext[0xa1d47a8~120~10.5.100.254~50876]

15:11:03.854 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:11:03.854 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d5188], socket(63)

15:11:03.854 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d5188], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(49664)

15:11:03.854 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d5188], socket(63)

15:11:03.854 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d5188)

15:11:03.854 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d5188])

15:11:03.854 |-->TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1d5188~121~10.5.100.254~49664]

15:11:03.854 |-->TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d5188~121~10.5.100.254~49664]

15:11:03.854 |CReqContext::CReqContext(0xa1d5b68)

15:11:03.861 |pollFor(1), Time(5), poll(30) Read Ready

15:11:03.861 |TID[a43d0b90] HTTPEngine::readRequest(), [0xa1d5188~121~10.5.100.254~49664], socket(30), received size[66]

15:11:03.861 | TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d5188~121~10.5.100.254~49664] socket(30), ReqTimeout[0], Request[GET /ITLSEP001D70FD338B.tlv HTTP/1.1

Host:10.0.100.10:2125620]

15:11:03.861 | TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d5188~121~10.5.100.254~49664] File Requested ITLSEP001D70FD338B.tlv

15:11:03.861 |<--TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d5188~121~10.5.100.254~49664]

15:11:03.861 |-->CReqContext::tftp[0xa1d5188~121~10.5.100.254~49664]

15:11:03.861 |CReqContext::CheckAndSetIsStatic(itlsep001d70fd338b.tlv) is (Not a Static) File

15:11:03.861 |CReqContext::isCTLCAPFRequest[ITLSEP001D70FD338B.tlv] Not a CTLCAPF File

15:11:03.861 | CReqContext::tftp[0xa1d5188~121~10.5.100.254~49664] HandleITL: [ITLSEP001D70FD338B.tlv] is ITLSEPMac.tlv file, Searching[001D70FD338B]

15:11:03.862 |TFTPCache::FindMatching(001D70FD338B), Found[SEP001D70FD338B.cnf.xml.sgn]

15:11:03.862 | CReqContext::tftp[0xa1d5188~121~10.5.100.254~49664] HandleITL: Match[001D70FD338B] Found, looking for [ITLFile.tlv] to serve

15:11:03.862 |CReqContext::FindAndServe SignFileFlag[0]

15:11:03.862 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

15:11:03.862 |CReqContext::FindAndServe(1)[0xa1d5188~121~10.5.100.254~49664],[(ITLFile.tlv),(2567),(0x9b0d108)] found in config cache

15:11:03.862 |-->CReqContext::serve[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |-->CSendBuffer::Init[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |<--CSendBuffer::Init[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |-->HTTPEngine::sendResponse[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 | HTTPEngine::sendResponse[0xa1d5188~121~10.5.100.254~49664] FileName[ITLSEP001D70FD338B.tlv], Version[HTTP/1.1], Size[2567]

15:11:03.862 | HTTPEngine::sendResponse[0xa1d5188~121~10.5.100.254~49664] [85][HTTP/1.1 200 OK

Content-length: 2567

Cache-Control: no-store

Content-type: */*

]

15:11:03.862 |-->HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 | HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664] Socket[30], Length[85]

15:11:03.862 | HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664] Send Complete, Size[85], Sent[85]

15:11:03.862 |<--HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |-->HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 | HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664] Socket[30], Length[2567]

15:11:03.862 | HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664] Send Complete, Size[2567], Sent[2567]

15:11:03.862 |<--HTTPEngine::sendMessage[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |<--HTTPEngine::sendResponse[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |<--CReqContext::serve[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |<--CReqContext::tftp[0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |<--TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1d5188~121~10.5.100.254~49664]

15:11:03.862 |TID[a43d0b90] CReqContext::~CReqContext[0xa1d5188~121~10.5.100.254~49664]

15:11:04.251 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:11:04.251 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d5b68], socket(63)

15:11:04.251 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d5b68], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(52100)

15:11:04.251 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d5b68], socket(63)

15:11:04.251 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d5b68)

15:11:04.251 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d5b68])

15:11:04.251 |-->TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1d5b68~122~10.5.100.254~52100]

15:11:04.251 |-->TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d5b68~122~10.5.100.254~52100]

15:11:04.251 |CReqContext::CReqContext(0xa1d6548)

15:11:04.259 |pollFor(1), Time(5), poll(30) Read Ready

15:11:04.259 |TID[a39d0b90] HTTPEngine::readRequest(), [0xa1d5b68~122~10.5.100.254~52100], socket(30), received size[71]

15:11:04.259 | TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d5b68~122~10.5.100.254~52100] socket(30), ReqTimeout[0], Request[GET /SEP001D70FD338B.cnf.xml.sgn HTTP/1.1

Host:10.0.100.10:2125620]

15:11:04.259 | TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d5b68~122~10.5.100.254~52100] File Requested SEP001D70FD338B.cnf.xml.sgn

15:11:04.259 |<--TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |-->CReqContext::tftp[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |CReqContext::CheckAndSetIsStatic(sep001d70fd338b.cnf.xml.sgn) is (Not a Static) File

15:11:04.259 |CReqContext::isCTLCAPFRequest[SEP001D70FD338B.cnf.xml.sgn] Not a CTLCAPF File

15:11:04.259 |CReqContext::FindAndServe SignFileFlag[0]

15:11:04.259 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

15:11:04.259 |CReqContext::FindAndServe(1)[0xa1d5b68~122~10.5.100.254~52100],[(SEP001D70FD338B.cnf.xml.sgn),(6425),(0xa503cd8)] found in config cache

15:11:04.259 |-->CReqContext::serve[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |-->CSendBuffer::Init[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |<--CSendBuffer::Init[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |-->HTTPEngine::sendResponse[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 | HTTPEngine::sendResponse[0xa1d5b68~122~10.5.100.254~52100] FileName[SEP001D70FD338B.cnf.xml.sgn], Version[HTTP/1.1], Size[6425]

15:11:04.259 | HTTPEngine::sendResponse[0xa1d5b68~122~10.5.100.254~52100] [85][HTTP/1.1 200 OK

Content-length: 6425

Cache-Control: no-store

Content-type: */*

]

15:11:04.259 |-->HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 | HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100] Socket[30], Length[85]

15:11:04.259 | HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100] Send Complete, Size[85], Sent[85]

15:11:04.259 |<--HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |-->HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 | HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100] Socket[30], Length[6425]

15:11:04.259 | HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100] Send Complete, Size[6425], Sent[6425]

15:11:04.259 |<--HTTPEngine::sendMessage[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |<--HTTPEngine::sendResponse[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |<--CReqContext::serve[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |<--CReqContext::tftp[0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |<--TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1d5b68~122~10.5.100.254~52100]

15:11:04.259 |TID[a39d0b90] CReqContext::~CReqContext[0xa1d5b68~122~10.5.100.254~52100]

15:11:05.723 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:11:05.723 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d6548], socket(63)

15:11:05.723 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d6548], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(52633)

15:11:05.723 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d6548], socket(63)

15:11:05.723 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d6548)

15:11:05.723 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d6548])

15:11:05.723 |CReqContext::CReqContext(0xa1d6f28)

15:11:05.723 |-->TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1d6548~123~10.5.100.254~52633]

15:11:05.723 |-->TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |pollFor(1), Time(5), poll(30) Read Ready

15:11:05.730 |TID[a1bd0b90] HTTPEngine::readRequest(), [0xa1d6548~123~10.5.100.254~52633], socket(30), received size[61]

15:11:05.730 | TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d6548~123~10.5.100.254~52633] socket(30), ReqTimeout[0], Request[GET /tzupdater.jar.sgn HTTP/1.1

Host:10.0.100.10:2125620]

15:11:05.730 | TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d6548~123~10.5.100.254~52633] File Requested tzupdater.jar.sgn

15:11:05.730 |<--TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |-->CReqContext::tftp[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |CReqContext::CheckAndSetIsStatic(tzupdater.jar.sgn) is (Static) File

15:11:05.730 |CReqContext::FindAndServe SignFileFlag[0]

15:11:05.730 |CReqContext::FindAndServe(1)[0xa1d6548~123~10.5.100.254~52633],[(tzupdater.jar.sgn),(253703),(0x8e12c40)] found in bin cache

15:11:05.730 |-->CReqContext::serve[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |-->CSendBuffer::Init[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |<--CSendBuffer::Init[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |-->HTTPEngine::sendResponse[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 | HTTPEngine::sendResponse[0xa1d6548~123~10.5.100.254~52633] FileName[tzupdater.jar.sgn], Version[HTTP/1.1], Size[253703]

15:11:05.730 | HTTPEngine::sendResponse[0xa1d6548~123~10.5.100.254~52633] [62][HTTP/1.1 200 OK

Content-length: 253703

Content-type: */*

]

15:11:05.730 |-->HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 | HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633] Socket[30], Length[62]

15:11:05.730 | HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633] Send Complete, Size[62], Sent[62]

15:11:05.730 |<--HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 |-->HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633]

15:11:05.730 | HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633] Socket[30], Length[253703]

15:11:10.598 | HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633] Send Complete, Size[253703], Sent[253703]

15:11:10.598 |<--HTTPEngine::sendMessage[0xa1d6548~123~10.5.100.254~52633]

15:11:10.598 |<--HTTPEngine::sendResponse[0xa1d6548~123~10.5.100.254~52633]

15:11:10.598 |<--CReqContext::serve[0xa1d6548~123~10.5.100.254~52633]

15:11:10.598 |<--CReqContext::tftp[0xa1d6548~123~10.5.100.254~52633]

15:11:10.598 |<--TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1d6548~123~10.5.100.254~52633]

15:11:10.598 |TID[a1bd0b90] CReqContext::~CReqContext[0xa1d6548~123~10.5.100.254~52633]

15:11:14.927 | TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9977489]

15:11:18.670 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:11:18.670 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d6f28], socket(63)

15:11:18.670 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d6f28], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(52400)

15:11:18.670 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d6f28], socket(63)

15:11:18.670 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d6f28)

15:11:18.670 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d6f28])

15:11:18.670 |CReqContext::CReqContext(0xa1d7908)

15:11:18.670 |-->TID[a25d0b90] CReqContext::HTTPProxyRun(), [0xa1d6f28~124~10.5.100.254~52400]

15:11:18.670 |-->TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d6f28~124~10.5.100.254~52400]

15:11:18.677 |pollFor(1), Time(5), poll(30) Read Ready

15:11:18.677 |TID[a25d0b90] HTTPEngine::readRequest(), [0xa1d6f28~124~10.5.100.254~52400], socket(30), received size[81]

15:11:18.678 | TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d6f28~124~10.5.100.254~52400] socket(30), ReqTimeout[0], Request[GET /English_United_States/mk-sccp.jar.sgn HTTP/1.1

Host:10.0.100.10:2125620]

15:11:18.678 | TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d6f28~124~10.5.100.254~52400] File Requested English_United_States/mk-sccp.jar.sgn

15:11:18.678 |<--TID[a25d0b90] HTTPEngine::getRequest(), [0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 |-->CReqContext::tftp[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 |CReqContext::CheckAndSetIsStatic(english_united_states/mk-sccp.jar.sgn) is (Static) File

15:11:18.678 |CReqContext::FindAndServe SignFileFlag[0]

15:11:18.678 |CReqContext::FindAndServe(1)[0xa1d6f28~124~10.5.100.254~52400],[(English_United_States/mk-sccp.jar.sgn),(58822),(0xa2e5ca8)] found in bin cache

15:11:18.678 |-->CReqContext::serve[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 |-->CSendBuffer::Init[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 |<--CSendBuffer::Init[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 |-->HTTPEngine::sendResponse[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 | HTTPEngine::sendResponse[0xa1d6f28~124~10.5.100.254~52400] FileName[English_United_States/mk-sccp.jar.sgn], Version[HTTP/1.1], Size[58822]

15:11:18.678 | HTTPEngine::sendResponse[0xa1d6f28~124~10.5.100.254~52400] [61][HTTP/1.1 200 OK

Content-length: 58822

Content-type: */*

]

15:11:18.678 |-->HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 | HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400] Socket[30], Length[61]

15:11:18.678 | HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400] Send Complete, Size[61], Sent[61]

15:11:18.678 |<--HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 |-->HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400]

15:11:18.678 | HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400] Socket[30], Length[58822]

15:11:19.184 | HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400] Send Complete, Size[58822], Sent[58822]

15:11:19.184 |<--HTTPEngine::sendMessage[0xa1d6f28~124~10.5.100.254~52400]

15:11:19.184 |<--HTTPEngine::sendResponse[0xa1d6f28~124~10.5.100.254~52400]

15:11:19.184 |<--CReqContext::serve[0xa1d6f28~124~10.5.100.254~52400]

15:11:19.184 |<--CReqContext::tftp[0xa1d6f28~124~10.5.100.254~52400]

15:11:19.184 |<--TID[a25d0b90] CReqContext::HTTPProxyRun(), [0xa1d6f28~124~10.5.100.254~52400]

15:11:19.184 |TID[a25d0b90] CReqContext::~CReqContext[0xa1d6f28~124~10.5.100.254~52400]

15:11:19.893 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:11:19.893 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d7908], socket(63)

15:11:19.893 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d7908], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(49280)

15:11:19.893 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d7908], socket(63)

15:11:19.893 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d7908)

15:11:19.893 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d7908])

15:11:19.893 |CReqContext::CReqContext(0xa1d82e8)

15:11:19.893 |-->TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa1d7908~125~10.5.100.254~49280]

15:11:19.893 |-->TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 |pollFor(1), Time(5), poll(30) Read Ready

15:11:19.900 |TID[a2fd0b90] HTTPEngine::readRequest(), [0xa1d7908~125~10.5.100.254~49280], socket(30), received size[74]

15:11:19.900 | TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d7908~125~10.5.100.254~49280] socket(30), ReqTimeout[0], Request[GET /United_States/g3-tones.xml.sgn HTTP/1.1

Host:10.0.100.10:2125620]

15:11:19.900 | TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d7908~125~10.5.100.254~49280] File Requested United_States/g3-tones.xml.sgn

15:11:19.900 |<--TID[a2fd0b90] HTTPEngine::getRequest(), [0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 |-->CReqContext::tftp[0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 |CReqContext::CheckAndSetIsStatic(united_states/g3-tones.xml.sgn) is (Static) File

15:11:19.900 |CReqContext::FindAndServe SignFileFlag[0]

15:11:19.900 |CReqContext::FindAndServe(1)[0xa1d7908~125~10.5.100.254~49280],[(United_States/g3-tones.xml.sgn),(2133),(0xa2f4768)] found in bin cache

15:11:19.900 |-->CReqContext::serve[0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 |-->CSendBuffer::Init[0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 |<--CSendBuffer::Init[0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 |-->HTTPEngine::sendResponse[0xa1d7908~125~10.5.100.254~49280]

15:11:19.900 | HTTPEngine::sendResponse[0xa1d7908~125~10.5.100.254~49280] FileName[United_States/g3-tones.xml.sgn], Version[HTTP/1.1], Size[2133]

15:11:19.901 | HTTPEngine::sendResponse[0xa1d7908~125~10.5.100.254~49280] [60][HTTP/1.1 200 OK

Content-length: 2133

Content-type: */*

]

15:11:19.901 |-->HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 | HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280] Socket[30], Length[60]

15:11:19.901 | HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280] Send Complete, Size[60], Sent[60]

15:11:19.901 |<--HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 |-->HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 | HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280] Socket[30], Length[2133]

15:11:19.901 | HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280] Send Complete, Size[2133], Sent[2133]

15:11:19.901 |<--HTTPEngine::sendMessage[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 |<--HTTPEngine::sendResponse[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 |<--CReqContext::serve[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 |<--CReqContext::tftp[0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 |<--TID[a2fd0b90] CReqContext::HTTPProxyRun(), [0xa1d7908~125~10.5.100.254~49280]

15:11:19.901 |TID[a2fd0b90] CReqContext::~CReqContext[0xa1d7908~125~10.5.100.254~49280]

15:11:45.056 | TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9977519]

15:12:15.190 | TID[05ed0b90] CServiceModule::TimerThread() signal reRead config[9977549]

15:12:23.894 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:12:23.894 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d82e8], socket(63)

15:12:23.894 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d82e8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(51586)

15:12:23.894 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d82e8], socket(63)

15:12:23.894 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d82e8)

15:12:23.894 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d82e8])

15:12:23.894 |-->TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1d82e8~126~10.5.100.254~51586]

15:12:23.894 |-->TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d82e8~126~10.5.100.254~51586]

15:12:23.894 |CReqContext::CReqContext(0xa1d8cc8)

15:12:23.897 |pollFor(1), Time(5), poll(30) Read Ready

15:12:23.897 |TID[a43d0b90] HTTPEngine::readRequest(), [0xa1d82e8~126~10.5.100.254~51586], socket(30), received size[66]

15:12:23.897 | TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d82e8~126~10.5.100.254~51586] socket(30), ReqTimeout[0], Request[GET /CTLSEP001D70FD338B.tlv HTTP/1.1

Host:10.0.100.10:2125620]

15:12:23.897 | TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d82e8~126~10.5.100.254~51586] File Requested CTLSEP001D70FD338B.tlv

15:12:23.897 |<--TID[a43d0b90] HTTPEngine::getRequest(), [0xa1d82e8~126~10.5.100.254~51586]

15:12:23.897 |-->CReqContext::tftp[0xa1d82e8~126~10.5.100.254~51586]

15:12:23.897 |CReqContext::CheckAndSetIsStatic(ctlsep001d70fd338b.tlv) is (Not a Static) File

15:12:23.897 |CReqContext::isCTLCAPFRequest[CTLSEP001D70FD338B.tlv] Not a CTLCAPF File

15:12:23.897 | CReqContext::tftp[0xa1d82e8~126~10.5.100.254~51586] HandleCTL: [CTLSEP001D70FD338B.tlv] is CTLSEPMac.tlv file, Searching[001D70FD338B]

15:12:23.898 |TFTPCache::FindMatching(001D70FD338B), Found[SEP001D70FD338B.cnf.xml.sgn]

15:12:23.898 | CReqContext::tftp[0xa1d82e8~126~10.5.100.254~51586] HandleCTL: Match[001D70FD338B] Found, looking for [CTLFile.tlv] to serve

15:12:23.898 |CReqContext::FindAndServe SignFileFlag[0]

15:12:23.898 |-->HTTPEngine::sendNak[0xa1d82e8~126~10.5.100.254~51586][1]

15:12:23.898 | HTTPEngine::sendNak[0xa1d82e8~126~10.5.100.254~51586][1] FileName[CTLSEP001D70FD338B.tlv], Version[HTTP/1.1]

15:12:23.898 | HTTPEngine::sendNak[0xa1d82e8~126~10.5.100.254~51586][1] [64][HTTP/1.1 404 Not Found

Content-length: 0

Content-type: */*

]

15:12:23.898 |-->HTTPEngine::sendMessage[0xa1d82e8~126~10.5.100.254~51586]

15:12:23.898 | HTTPEngine::sendMessage[0xa1d82e8~126~10.5.100.254~51586] Socket[30], Length[64]

15:12:23.898 | HTTPEngine::sendMessage[0xa1d82e8~126~10.5.100.254~51586] Send Complete, Size[64], Sent[64]

15:12:23.898 |<--HTTPEngine::sendMessage[0xa1d82e8~126~10.5.100.254~51586]

15:12:23.898 |<--HTTPEngine::sendNak[0xa1d82e8~126~10.5.100.254~51586][1]

15:12:23.898 |<--CReqContext::tftp[0xa1d82e8~126~10.5.100.254~51586]

15:12:23.898 |<--TID[a43d0b90] CReqContext::HTTPProxyRun(), [0xa1d82e8~126~10.5.100.254~51586]

15:12:23.898 |TID[a43d0b90] CReqContext::~CReqContext[0xa1d82e8~126~10.5.100.254~51586]

15:12:24.089 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:12:24.089 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d8cc8], socket(63)

15:12:24.089 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d8cc8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(50736)

15:12:24.089 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d8cc8], socket(63)

15:12:24.089 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d8cc8)

15:12:24.089 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d8cc8])

15:12:24.089 |-->TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.089 |-->TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.090 |CReqContext::CReqContext(0xa1d96a8)

15:12:24.100 |pollFor(1), Time(5), poll(30) Read Ready

15:12:24.100 |TID[a39d0b90] HTTPEngine::readRequest(), [0xa1d8cc8~127~10.5.100.254~50736], socket(30), received size[66]

15:12:24.100 | TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d8cc8~127~10.5.100.254~50736] socket(30), ReqTimeout[0], Request[GET /ITLSEP001D70FD338B.tlv HTTP/1.1

Host:10.0.100.10:2125620]

15:12:24.100 | TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d8cc8~127~10.5.100.254~50736] File Requested ITLSEP001D70FD338B.tlv

15:12:24.100 |<--TID[a39d0b90] HTTPEngine::getRequest(), [0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.100 |-->CReqContext::tftp[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.100 |CReqContext::CheckAndSetIsStatic(itlsep001d70fd338b.tlv) is (Not a Static) File

15:12:24.100 |CReqContext::isCTLCAPFRequest[ITLSEP001D70FD338B.tlv] Not a CTLCAPF File

15:12:24.100 | CReqContext::tftp[0xa1d8cc8~127~10.5.100.254~50736] HandleITL: [ITLSEP001D70FD338B.tlv] is ITLSEPMac.tlv file, Searching[001D70FD338B]

15:12:24.101 |TFTPCache::FindMatching(001D70FD338B), Found[SEP001D70FD338B.cnf.xml.sgn]

15:12:24.101 | CReqContext::tftp[0xa1d8cc8~127~10.5.100.254~50736] HandleITL: Match[001D70FD338B] Found, looking for [ITLFile.tlv] to serve

15:12:24.101 |CReqContext::FindAndServe SignFileFlag[0]

15:12:24.101 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

15:12:24.101 |CReqContext::FindAndServe(1)[0xa1d8cc8~127~10.5.100.254~50736],[(ITLFile.tlv),(2567),(0x9b0d108)] found in config cache

15:12:24.101 |-->CReqContext::serve[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |-->CSendBuffer::Init[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |<--CSendBuffer::Init[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |-->HTTPEngine::sendResponse[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 | HTTPEngine::sendResponse[0xa1d8cc8~127~10.5.100.254~50736] FileName[ITLSEP001D70FD338B.tlv], Version[HTTP/1.1], Size[2567]

15:12:24.101 | HTTPEngine::sendResponse[0xa1d8cc8~127~10.5.100.254~50736] [85][HTTP/1.1 200 OK

Content-length: 2567

Cache-Control: no-store

Content-type: */*

]

15:12:24.101 |-->HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 | HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736] Socket[30], Length[85]

15:12:24.101 | HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736] Send Complete, Size[85], Sent[85]

15:12:24.101 |<--HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |-->HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 | HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736] Socket[30], Length[2567]

15:12:24.101 | HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736] Send Complete, Size[2567], Sent[2567]

15:12:24.101 |<--HTTPEngine::sendMessage[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |<--HTTPEngine::sendResponse[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |<--CReqContext::serve[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |<--CReqContext::tftp[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |<--TID[a39d0b90] CReqContext::HTTPProxyRun(), [0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.101 |TID[a39d0b90] CReqContext::~CReqContext[0xa1d8cc8~127~10.5.100.254~50736]

15:12:24.531 |RequestPoller::pollForRequest(30), fd(63) Read Ready

15:12:24.531 |-->TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d96a8], socket(63)

15:12:24.531 | TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d96a8], socket(63) accept() success, Socket(30), RemoteIP(10.5.100.254), Port(50797)

15:12:24.531 |<--TID[971d0b90] HTTPEngine::acceptConnect(), [0xa1d96a8], socket(63)

15:12:24.531 | TID[971d0b90] TFTPServer::waitForHTTPConnection[0x8915b38]() sockets:63 count(00000), connect(0xa1d96a8)

15:12:24.531 |TID[971d0b90] CReqProcessThreads::processHttpReq(push CReqContext[0xa1d96a8])

15:12:24.531 |CReqContext::CReqContext(0xa1da088)

15:12:24.531 |-->TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1d96a8~128~10.5.100.254~50797]

15:12:24.531 |-->TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d96a8~128~10.5.100.254~50797]

15:12:24.537 |pollFor(1), Time(5), poll(30) Read Ready

15:12:24.537 |TID[a1bd0b90] HTTPEngine::readRequest(), [0xa1d96a8~128~10.5.100.254~50797], socket(30), received size[71]

15:12:24.538 | TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d96a8~128~10.5.100.254~50797] socket(30), ReqTimeout[0], Request[GET /SEP001D70FD338B.cnf.xml.sgn HTTP/1.1

Host:10.0.100.10:2125620]

15:12:24.538 | TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d96a8~128~10.5.100.254~50797] File Requested SEP001D70FD338B.cnf.xml.sgn

15:12:24.538 |<--TID[a1bd0b90] HTTPEngine::getRequest(), [0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |-->CReqContext::tftp[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |CReqContext::CheckAndSetIsStatic(sep001d70fd338b.cnf.xml.sgn) is (Not a Static) File

15:12:24.538 |CReqContext::isCTLCAPFRequest[SEP001D70FD338B.cnf.xml.sgn] Not a CTLCAPF File

15:12:24.538 |CReqContext::FindAndServe SignFileFlag[0]

15:12:24.538 |CCtftpChangeNotifyServer::HasWaitingEvent(), Empty List or pkid

15:12:24.538 |CReqContext::FindAndServe(1)[0xa1d96a8~128~10.5.100.254~50797],[(SEP001D70FD338B.cnf.xml.sgn),(6425),(0xa503cd8)] found in config cache

15:12:24.538 |-->CReqContext::serve[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |-->CSendBuffer::Init[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |<--CSendBuffer::Init[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |-->HTTPEngine::sendResponse[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 | HTTPEngine::sendResponse[0xa1d96a8~128~10.5.100.254~50797] FileName[SEP001D70FD338B.cnf.xml.sgn], Version[HTTP/1.1], Size[6425]

15:12:24.538 | HTTPEngine::sendResponse[0xa1d96a8~128~10.5.100.254~50797] [85][HTTP/1.1 200 OK

Content-length: 6425

Cache-Control: no-store

Content-type: */*

]

15:12:24.538 |-->HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 | HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797] Socket[30], Length[85]

15:12:24.538 | HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797] Send Complete, Size[85], Sent[85]

15:12:24.538 |<--HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |-->HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 | HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797] Socket[30], Length[6425]

15:12:24.538 | HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797] Send Complete, Size[6425], Sent[6425]

15:12:24.538 |<--HTTPEngine::sendMessage[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |<--HTTPEngine::sendResponse[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |<--CReqContext::serve[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |<--CReqContext::tftp[0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |<--TID[a1bd0b90] CReqContext::HTTPProxyRun(), [0xa1d96a8~128~10.5.100.254~50797]

15:12:24.538 |TID[a1bd0b90] CReqContext::~CReqContext[0xa1d96a8~128~10.5.100.254~50797]