log.txt

2016-09-26 15:46:09.014847 UTC - [Main Thread]: D/nsHttp Creating nsHttpHandler [this=7f64a3b87000].
2016-09-26 15:46:09.014883 UTC - [Main Thread]: D/nsHttp nsHttpHandler::Init
2016-09-26 15:46:09.014998 UTC - [Main Thread]: D/nsHttp nsHttpHandler::PrefsChanged [pref=(null)]
2016-09-26 15:46:09.015008 UTC - [Main Thread]: D/nsHttp nsHttpHandler::PrefsChanged Security Pref Changed (null)
2016-09-26 15:46:09.015514 UTC - [Main Thread]: D/nsHttp nsHttpHandler::MakeNewRequestTokenBucket this=7f64a3b87000 child=0
2016-09-26 15:46:09.015594 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::Init
2016-09-26 15:46:09.015599 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::Init
2016-09-26 15:46:09.015611 UTC - [Main Thread]: V/nsHttp Creating nsHttpConnectionMgr @7f64ae47e1e0
2016-09-26 15:46:09.015615 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::Init
2016-09-26 15:46:09.015625 UTC - [Main Thread]: D/nsHttp > legacy-app-name = Mozilla
2016-09-26 15:46:09.015628 UTC - [Main Thread]: D/nsHttp > legacy-app-version = 5.0
2016-09-26 15:46:09.015631 UTC - [Main Thread]: D/nsHttp > platform = X11
2016-09-26 15:46:09.015633 UTC - [Main Thread]: D/nsHttp > oscpu = Linux x86_64
2016-09-26 15:46:09.015635 UTC - [Main Thread]: D/nsHttp > misc = rv:52.0
2016-09-26 15:46:09.015638 UTC - [Main Thread]: D/nsHttp > product = Gecko
2016-09-26 15:46:09.015640 UTC - [Main Thread]: D/nsHttp > product-sub = 20100101
2016-09-26 15:46:09.015643 UTC - [Main Thread]: D/nsHttp > app-name = 
2016-09-26 15:46:09.015645 UTC - [Main Thread]: D/nsHttp > app-version = 52.0a1
2016-09-26 15:46:09.015648 UTC - [Main Thread]: D/nsHttp > compat-firefox = Firefox/52.0
2016-09-26 15:46:09.015650 UTC - [Main Thread]: D/nsHttp nsHttpHandler::BuildUserAgent
2016-09-26 15:46:09.015658 UTC - [Main Thread]: D/nsHttp > user-agent = Mozilla/5.0 (X11; Linux x86_64; rv:52.0) Gecko/20100101 /52.0a1
2016-09-26 15:46:09.015689 UTC - [Main Thread]: D/nsHttp nsHttpHandler::MakeNewRequestTokenBucket this=7f64a3b87000 child=0
2016-09-26 15:46:09.017699 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NewProxiedChannel [proxyInfo=0]
2016-09-26 15:46:09.017756 UTC - [Main Thread]: V/nsHttp Creating HttpBaseChannel @a3b57000
2016-09-26 15:46:09.017763 UTC - [Main Thread]: D/nsHttp Creating nsHttpChannel [this=7f64a3b57000]
2016-09-26 15:46:09.023240 UTC - [Main Thread]: V/nsHttp HttpBaseChannel::Init [this=7f64a3b57000]
2016-09-26 15:46:09.023261 UTC - [Main Thread]: V/nsHttp host=localhost port=35255
2016-09-26 15:46:09.023265 UTC - [Main Thread]: V/nsHttp uri=http://localhost:35255/content
2016-09-26 15:46:09.023296 UTC - [Main Thread]: D/nsHttp nsHttpChannel::Init [this=7f64a3b57000]
2016-09-26 15:46:09.024591 UTC - [Main Thread]: D/nsHttp nsHttpChannel::AsyncOpen [this=7f64a3b57000]
2016-09-26 15:46:09.136531 UTC - [Main Thread]: V/nsHttp HttpBaseChannel::SetRequestHeader [this=7f64a3b57000 header="Cookie" value="" merge=0]
2016-09-26 15:46:09.136596 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3b57058 event="http-on-opening-request"]
2016-09-26 15:46:09.136686 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ResolveProxy [this=7f64a3b57000]
2016-09-26 15:46:09.141029 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnProxyAvailable [this=7f64a3b57000 pi=0 status=0 mStatus=0]
2016-09-26 15:46:09.141071 UTC - [Main Thread]: D/nsHttp nsHttpChannel::BeginConnect [this=7f64a3b57000]
2016-09-26 15:46:09.141091 UTC - [Main Thread]: D/nsHttp host=localhost port=35255
2016-09-26 15:46:09.141101 UTC - [Main Thread]: D/nsHttp uri=http://localhost:35255/content
2016-09-26 15:46:09.141595 UTC - [Main Thread]: D/nsHttp AltSvcCache::LookupMapping 7f64ae47e1e8 http:localhost:35255:.
2016-09-26 15:46:09.141755 UTC - [Main Thread]: D/nsHttp AltSvcCache::LookupMapping 7f64ae47e1e8 MISS
2016-09-26 15:46:09.141805 UTC - [Main Thread]: D/nsHttp AltSvcCache::GetAltServiceMapping 7f64ae47e1e8 key=http:localhost:35255:. existing=0 validated=0 ttl=0
2016-09-26 15:46:09.141822 UTC - [Main Thread]: D/nsHttp nsHttpChannel 7f64a3b57000 Using default connection info
2016-09-26 15:46:09.141841 UTC - [Main Thread]: V/nsHttp Init nsHttpConnectionInfo @7f64ae478be0
2016-09-26 15:46:09.142047 UTC - [Main Thread]: D/nsHttp nsHttpChannelAuthProvider::AddAuthorizationHeaders? [this=7f64a1c93300 channel=7f64a3b57538]
2016-09-26 15:46:09.142099 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::GetAuthEntryForPath [key=http://localhost:35255 path=/]
2016-09-26 15:46:09.142124 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3b57058 event="http-on-modify-request"]
2016-09-26 15:46:09.142191 UTC - [Main Thread]: D/nsHttp nsHttpChannel::BeginConnect [this=7f64a3b57000] prefetching
2016-09-26 15:46:09.142507 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ContinueBeginConnectWithResult [this=7f64a3b57000]
2016-09-26 15:46:09.142541 UTC - [Main Thread]: D/nsHttp nsHttpChannel::Connect [this=7f64a3b57000]
2016-09-26 15:46:09.142850 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::SpeculativeConnect [ci=......localhost:35255]
2016-09-26 15:46:09.143054 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OpenCacheEntry [this=7f64a3b57000]
2016-09-26 15:46:09.143146 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgSpeculativeConnect [ci=......localhost:35255]
2016-09-26 15:46:09.143204 UTC - [Socket Thread]: V/nsHttp Init nsHttpConnectionInfo @7f64ae478e80
2016-09-26 15:46:09.143291 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::AtActiveConnectionLimit [ci=......localhost:35255 caps=401]
2016-09-26 15:46:09.143315 UTC - [Socket Thread]: V/nsHttp    connection count = 0, limit 6
2016-09-26 15:46:09.143331 UTC - [Socket Thread]: V/nsHttp   result: false
2016-09-26 15:46:09.143357 UTC - [Socket Thread]: V/nsHttp Creating nsHalfOpenSocket [this=7f64a3db3e20 trans=7f64a1c905c0 ent=localhost key=......localhost:35255]
2016-09-26 15:46:09.143489 UTC - [Main Thread]: D/nsHttp nsHttpChannel::Connect 7f64a3b57000 AwaitingCacheCallbacks forces async
2016-09-26 15:46:09.143531 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ContinueBeginConnectWithResult result [this=7f64a3b57000 rv=0 mCanceled=0]
2016-09-26 15:46:09.143588 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::SetupStreams [this=7f64a3db3e20 ent=......localhost:35255] setup routed transport to origin localhost:35255 via :443
2016-09-26 15:46:09.143822 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ActivateTimeoutTick() this=7f64ae47e1e0 mTimeoutTick=0
2016-09-26 15:46:09.143921 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::SetupPrimaryStream [this=7f64a3db3e20 ent=localhost rv=0]
2016-09-26 15:46:09.144240 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::SetupBackupTimer() [this=7f64a3db3e20], did not arm
2016-09-26 15:46:09.144580 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::OnOutputStreamReady [this=7f64a3db3e20 ent=localhost primary]
2016-09-26 15:46:09.144615 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick armed=1 active=0
2016-09-26 15:46:09.144662 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick stop==true
2016-09-26 15:46:09.144714 UTC - [Socket Thread]: V/nsHttp Creating nsHttpConnection @7f64a48d13e0
2016-09-26 15:46:09.144744 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::OnOutputStreamReady Created new nshttpconnection 7f64a48d13e0
2016-09-26 15:46:09.144770 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Init this=7f64a48d13e0
2016-09-26 15:46:09.144839 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::OnOutputStreamReady no transaction match returning conn 7f64a48d13e0 to pool
2016-09-26 15:46:09.144862 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgReclaimConnection [conn=7f64a48d1418]
2016-09-26 15:46:09.144885 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d13e0 caps=0x401 ......localhost:35255
2016-09-26 15:46:09.144909 UTC - [Socket Thread]: V/nsHttp   adding connection to idle list
2016-09-26 15:46:09.144924 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::BeginIdleMonitoring [this=7f64a48d13e0]
2016-09-26 15:46:09.144935 UTC - [Socket Thread]: V/nsHttp Entering Idle Monitoring Mode [this=7f64a48d13e0]
2016-09-26 15:46:09.144950 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::PruneDeadConnectionsAfter
2016-09-26 15:46:09.145009 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.145030 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=1 queued=0]
2016-09-26 15:46:09.145047 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=1 queued=0]
2016-09-26 15:46:09.145062 UTC - [Socket Thread]: V/nsHttp Destroying nsHalfOpenSocket [this=7f64a3db3e20]
2016-09-26 15:46:09.145198 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.145282 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.145303 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=1 queued=0]
2016-09-26 15:46:09.145319 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=1 queued=0]
2016-09-26 15:46:09.167931 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnLookupComplete [this=7f64a3b57000] prefetch complete: success status[0x0]
2016-09-26 15:46:09.169435 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnCacheEntryAvailable [this=7f64a3b57000 entry=7f64a1c897e0 new=1 appcache=0 status=0 mAppCache=0 mAppCacheForWrite=0]
2016-09-26 15:46:09.169476 UTC - [Main Thread]: D/nsHttp nsHttpChannel::SetupTransaction [this=7f64a3b57000]
2016-09-26 15:46:09.169502 UTC - [Main Thread]: D/nsHttp Creating nsHttpTransaction @7f64a1c9c000
2016-09-26 15:46:09.169509 UTC - [Main Thread]: D/nsHttp nsHttpChannel 7f64a3b57000 created nsHttpTransaction 7f64a1c9c000
2016-09-26 15:46:09.169516 UTC - [Main Thread]: D/nsHttp nsHttpTransaction::Init [this=7f64a1c9c000 caps=1]
2016-09-26 15:46:09.169562 UTC - [Main Thread]: I/nsHttp http request [
2016-09-26 15:46:09.169582 UTC - [Main Thread]: I/nsHttp   GET /content HTTP/1.1
2016-09-26 15:46:09.169587 UTC - [Main Thread]: I/nsHttp   Host: localhost:35255
2016-09-26 15:46:09.169594 UTC - [Main Thread]: I/nsHttp   User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:52.0) Gecko/20100101 /52.0a1
2016-09-26 15:46:09.169601 UTC - [Main Thread]: I/nsHttp   Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
2016-09-26 15:46:09.169606 UTC - [Main Thread]: I/nsHttp   Accept-Language: en-US,en;q=0.5
2016-09-26 15:46:09.169610 UTC - [Main Thread]: I/nsHttp   Accept-Encoding: gzip, deflate
2016-09-26 15:46:09.169615 UTC - [Main Thread]: I/nsHttp   Connection: keep-alive
2016-09-26 15:46:09.169619 UTC - [Main Thread]: I/nsHttp ]
2016-09-26 15:46:09.169654 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::AddTransaction [trans=7f64a1c9c000 0]
2016-09-26 15:46:09.169725 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgNewTransaction [trans=7f64a1c9c028]
2016-09-26 15:46:09.169751 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d13e0 caps=0x401 ......localhost:35255
2016-09-26 15:46:09.169832 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::TryDispatchTransaction without conn [trans=7f64a1c9c000 ci=7f64ae478e80 ci=......localhost:35255 caps=1 tunnelprovider=0 onlyreused=0 active=0 idle=1]
2016-09-26 15:46:09.169859 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d13e0 caps=0x401 ......localhost:35255
2016-09-26 15:46:09.169877 UTC - [Socket Thread]: V/nsHttp    reusing connection [conn=7f64a48d13e0]
2016-09-26 15:46:09.169885 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::EndIdleMonitoring [this=7f64a48d13e0]
2016-09-26 15:46:09.169893 UTC - [Socket Thread]: V/nsHttp Leaving Idle Monitoring Mode [this=7f64a48d13e0]
2016-09-26 15:46:09.169901 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::StopPruneDeadConnectionsTimer
2016-09-26 15:46:09.169921 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ActivateTimeoutTick() this=7f64ae47e1e0 mTimeoutTick=7f64a1c8ada0
2016-09-26 15:46:09.169959 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::DispatchTransaction [ent-ci=......localhost:35255 7f64a1c8b040 trans=7f64a1c9c000 caps=1 conn=7f64a48d13e0 priority=0]
2016-09-26 15:46:09.169971 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::DispatchAbstractTransaction [ci=......localhost:35255 trans=7f64a1c9c000 caps=1 conn=7f64a48d13e0]
2016-09-26 15:46:09.169978 UTC - [Socket Thread]: V/nsHttp    not using pipeline datastructure due to class solo.
2016-09-26 15:46:09.169989 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Activate [this=7f64a48d13e0 trans=7f64a1c9c000 caps=1]
2016-09-26 15:46:09.170019 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d13e0 caps=0x1 ......localhost:35255
2016-09-26 15:46:09.170033 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::StartShortLivedTCPKeepalives[7f64a48d13e0] idle time[10s].
2016-09-26 15:46:09.170080 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketWritable [this=7f64a48d13e0] host=localhost
2016-09-26 15:46:09.170095 UTC - [Socket Thread]: V/nsHttp   writing transaction request stream
2016-09-26 15:46:09.170105 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::GetSecurityInfo trans=7f64a1c9c000 tlsfilter=0 socket=7f64a1c9ac18
2016-09-26 15:46:09.170155 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9c000 status=804b0005 progress=287]
2016-09-26 15:46:09.170168 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnTransportStatus 7f64a1c9c000 SENDING_TO without request body
2016-09-26 15:46:09.170180 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketWritable 7f64a48d13e0 ReadSegments returned [rv=0 read=287 sock-cond=0]
2016-09-26 15:46:09.170187 UTC - [Socket Thread]: V/nsHttp   writing transaction request stream
2016-09-26 15:46:09.170197 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketWritable 7f64a48d13e0 ReadSegments returned [rv=0 read=0 sock-cond=0]
2016-09-26 15:46:09.170205 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9c000 status=804b000a progress=0]
2016-09-26 15:46:09.170224 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::ResumeRecv [this=7f64a48d13e0]
2016-09-26 15:46:09.170346 UTC - [Socket Thread]: V/nsHttp    dispatched step 2 (idle) trans=7f64a1c9c000
2016-09-26 15:46:09.170357 UTC - [Socket Thread]: V/nsHttp   ProcessNewTransaction Dispatch Immediately trans=7f64a1c9c000
2016-09-26 15:46:09.256745 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable [this=7f64a48d13e0]
2016-09-26 15:46:09.256774 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::WriteSegments 7f64a1c9c000 reentrantFlag=0
2016-09-26 15:46:09.256813 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9c000 status=804b0006 progress=182]
2016-09-26 15:46:09.256826 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ProcessData [this=7f64a1c9c000 count=182]
2016-09-26 15:46:09.256833 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseHead [count=182]
2016-09-26 15:46:09.256849 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [HTTP/1.1 200 OK]
2016-09-26 15:46:09.256858 UTC - [Socket Thread]: D/nsHttp nsHttpResponseHead::ParseVersion [version=HTTP/1.1 200 OK]
2016-09-26 15:46:09.256880 UTC - [Socket Thread]: D/nsHttp Have status line [version=11 status=200 statusText=OK]
2016-09-26 15:46:09.256894 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [content-type: text/plain]
2016-09-26 15:46:09.256915 UTC - [Socket Thread]: D/nsHttp ParseContentType [type=text/plain]
2016-09-26 15:46:09.256930 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [cache-control: no-cache]
2016-09-26 15:46:09.256950 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [etag: test-etag1]
2016-09-26 15:46:09.256967 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [connection: close]
2016-09-26 15:46:09.256989 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [server: httpd.js]
2016-09-26 15:46:09.257009 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [date: Mon, 26 Sep 2016 15:46:09 GMT]
2016-09-26 15:46:09.257036 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [content-length: 13]
2016-09-26 15:46:09.257063 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContent [this=7f64a1c9c000 count=0]
2016-09-26 15:46:09.257074 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContentStart [this=7f64a1c9c000]
2016-09-26 15:46:09.257080 UTC - [Socket Thread]: I/nsHttp http response [
2016-09-26 15:46:09.257113 UTC - [Socket Thread]: I/nsHttp   HTTP/1.1 200 OK
2016-09-26 15:46:09.257124 UTC - [Socket Thread]: I/nsHttp   Content-Type: text/plain
2016-09-26 15:46:09.257131 UTC - [Socket Thread]: I/nsHttp   Cache-Control: no-cache
2016-09-26 15:46:09.257137 UTC - [Socket Thread]: I/nsHttp   Etag: test-etag1
2016-09-26 15:46:09.257143 UTC - [Socket Thread]: I/nsHttp   Connection: close
2016-09-26 15:46:09.257149 UTC - [Socket Thread]: I/nsHttp   Server: httpd.js
2016-09-26 15:46:09.257156 UTC - [Socket Thread]: I/nsHttp   Date: Mon, 26 Sep 2016 15:46:09 GMT
2016-09-26 15:46:09.257162 UTC - [Socket Thread]: I/nsHttp   Content-Length: 13
2016-09-26 15:46:09.257168 UTC - [Socket Thread]: I/nsHttp     OriginalHeaders
2016-09-26 15:46:09.257174 UTC - [Socket Thread]: I/nsHttp   Content-Type: text/plain
2016-09-26 15:46:09.257180 UTC - [Socket Thread]: I/nsHttp   Cache-Control: no-cache
2016-09-26 15:46:09.257186 UTC - [Socket Thread]: I/nsHttp   Etag: test-etag1
2016-09-26 15:46:09.257192 UTC - [Socket Thread]: I/nsHttp   Connection: close
2016-09-26 15:46:09.257198 UTC - [Socket Thread]: I/nsHttp   Server: httpd.js
2016-09-26 15:46:09.257204 UTC - [Socket Thread]: I/nsHttp   Date: Mon, 26 Sep 2016 15:46:09 GMT
2016-09-26 15:46:09.257210 UTC - [Socket Thread]: I/nsHttp   Content-Length: 13
2016-09-26 15:46:09.257215 UTC - [Socket Thread]: I/nsHttp ]
2016-09-26 15:46:09.257226 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnHeadersAvailable [this=7f64a48d13e0 trans=7f64a1c9c000 response-head=7f64a3be3780]
2016-09-26 15:46:09.257249 UTC - [Socket Thread]: V/nsHttp Assessing red penalty to localhost class 3 for event 131075. Penalty now 0, throttle[3] = 250
2016-09-26 15:46:09.257275 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContent [this=7f64a1c9c000 count=0 read=0 mContentRead=0 mContentLength=13]
2016-09-26 15:46:09.257286 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable 7f64a48d13e0 trans->ws rv=0 n=0 socketin=0
2016-09-26 15:46:09.257294 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::WriteSegments 7f64a1c9c000 reentrantFlag=0
2016-09-26 15:46:09.257310 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable 7f64a48d13e0 trans->ws rv=0 n=0 socketin=80470007
2016-09-26 15:46:09.257319 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::ResumeRecv [this=7f64a48d13e0]
2016-09-26 15:46:09.263411 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable [this=7f64a48d13e0]
2016-09-26 15:46:09.263427 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::WriteSegments 7f64a1c9c000 reentrantFlag=0
2016-09-26 15:46:09.263444 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9c000 status=804b0006 progress=195]
2016-09-26 15:46:09.263454 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ProcessData [this=7f64a1c9c000 count=13]
2016-09-26 15:46:09.263460 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContent [this=7f64a1c9c000 count=13]
2016-09-26 15:46:09.263468 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContent [this=7f64a1c9c000 count=13 read=13 mContentRead=13 mContentLength=13]
2016-09-26 15:46:09.263475 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction 7f64a1c9c000 request context set to null in ReleaseBlockingTransaction() - was 0
2016-09-26 15:46:09.263497 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable 7f64a48d13e0 trans->ws rv=0 n=13 socketin=0
2016-09-26 15:46:09.263506 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::WriteSegments 7f64a1c9c000 reentrantFlag=0
2016-09-26 15:46:09.263513 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable 7f64a48d13e0 trans->ws rv=80470002 n=13 socketin=0
2016-09-26 15:46:09.263525 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::CloseTransaction[this=7f64a48d13e0 trans=7f64a1c9c000 reason=80470002]
2016-09-26 15:46:09.263532 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::Close [this=7f64a1c9c000 reason=0]
2016-09-26 15:46:09.263542 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ReclaimConnection [conn=7f64a48d13e0]
2016-09-26 15:46:09.263578 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction 7f64a1c9c000 request context set to null in ReleaseBlockingTransaction() - was 0
2016-09-26 15:46:09.263613 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgReclaimConnection [conn=7f64a48d1418]
2016-09-26 15:46:09.263627 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick armed=1 active=0
2016-09-26 15:46:09.263633 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick stop==true
2016-09-26 15:46:09.263645 UTC - [Socket Thread]: V/nsHttp   connection cannot be reused; closing connection
2016-09-26 15:46:09.263652 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Close [this=7f64a48d13e0 reason=80004004]
2016-09-26 15:46:09.263666 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Close drained 0 bytes
2016-09-26 15:46:09.263685 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.263696 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=0 queued=0]
2016-09-26 15:46:09.263704 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=0 queued=0]
2016-09-26 15:46:09.263712 UTC - [Socket Thread]: V/nsHttp Destroying nsHttpConnection @7f64a48d13e0
2016-09-26 15:46:09.263718 UTC - [Socket Thread]: V/nsHttp nsHttpConnection 7f64a48d13e0 performed 1 HTTP/1.x transactions
2016-09-26 15:46:09.263791 UTC - [Socket Thread]: V/nsHttp nsHttpConnection 7f64a48d13e0 read 0kb on connection spdy=0
2016-09-26 15:46:09.265326 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnStartRequest [this=7f64a3b57000 request=7f64a3b72c80 status=0]
2016-09-26 15:46:09.265338 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ProcessResponse [this=7f64a3b57000 httpStatus=200]
2016-09-26 15:46:09.265347 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3b57058 event="http-on-examine-response"]
2016-09-26 15:46:09.265357 UTC - [Main Thread]: D/nsHttp nsHttpChannelAuthProvider::CheckForSuperfluousAuth? [this=7f64a1c93300 channel=7f64a3b57538]
2016-09-26 15:46:09.265363 UTC - [Main Thread]: D/nsHttp   continuation state has been reset
2016-09-26 15:46:09.265367 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ProcessNormal [this=7f64a3b57000]
2016-09-26 15:46:09.265373 UTC - [Main Thread]: D/nsHttp nsHttpChannel::InitCacheEntry [this=7f64a3b57000 entry=7f64a1c897e0]
2016-09-26 15:46:09.265376 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::MustValidate ??
2016-09-26 15:46:09.265379 UTC - [Main Thread]: D/nsHttp Must validate since response contains 'no-cache' header
2016-09-26 15:46:09.265410 UTC - [Main Thread]: D/nsHttp nsHttpChannel::AddCacheEntryHeaders [this=7f64a3b57000] begin
2016-09-26 15:46:09.265460 UTC - [Main Thread]: D/nsHttp   calling mListener->OnStartRequest
2016-09-26 15:46:09.267458 UTC - [Main Thread]: V/nsHttp HttpBaseChannel::DoApplyContentConversions [this=7f64a3b57000]
2016-09-26 15:46:09.267470 UTC - [Main Thread]: D/nsHttp Preparing to write data into the cache [uri=http://localhost:35255/content]
2016-09-26 15:46:09.267486 UTC - [Main Thread]: D/nsHttp Trading cache input stream for output stream [channel=7f64a3b57000]
2016-09-26 15:46:09.267520 UTC - [Main Thread]: D/nsHttp nsHttpChannel::InstallCacheListener sync tee 7f64a1cbace0 rv=0 cacheIOTarget=0
2016-09-26 15:46:09.267533 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnDataAvailable [this=7f64a3b57000 request=7f64a3b72c80 offset=0 count=13]
2016-09-26 15:46:09.268318 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnStopRequest [this=7f64a3b57000 request=7f64a3b72c80 status=0]
2016-09-26 15:46:09.268326 UTC - [Main Thread]: D/nsHttp   dropping upload stream
2016-09-26 15:46:09.268330 UTC - [Main Thread]: D/nsHttp nsHttpChannel 7f64a3b57000 has a strongly framed transaction: 1
2016-09-26 15:46:09.268335 UTC - [Main Thread]: D/nsHttp nsHttpTransaction::DeleteSelfOnConsumerThread [this=7f64a1c9c000]
2016-09-26 15:46:09.268338 UTC - [Main Thread]: D/nsHttp Destroying nsHttpTransaction @7f64a1c9c000
2016-09-26 15:46:09.268342 UTC - [Main Thread]: D/nsHttp nsHttpTransaction 7f64a1c9c000 request context set to null in ReleaseBlockingTransaction() - was 0
2016-09-26 15:46:09.268355 UTC - [Main Thread]: D/nsHttp nsHttpChannel::FinalizeCacheEntry [this=7f64a3b57000]
2016-09-26 15:46:09.268359 UTC - [Main Thread]: D/nsHttp nsHttpChannel::FinalizeCacheEntry [this=7f64a3b57000] Is Strongly Framed
2016-09-26 15:46:09.268372 UTC - [Main Thread]: D/nsHttp   calling OnStopRequest
2016-09-26 15:46:09.287110 UTC - [Main Thread]: D/nsHttp nsHttpChannel::CloseCacheEntry [this=7f64a3b57000] mStatus=0 mCacheEntryIsWriteOnly=1
2016-09-26 15:46:09.374457 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NewProxiedChannel [proxyInfo=0]
2016-09-26 15:46:09.374499 UTC - [Main Thread]: V/nsHttp Creating HttpBaseChannel @a3bc4800
2016-09-26 15:46:09.374527 UTC - [Main Thread]: D/nsHttp Creating nsHttpChannel [this=7f64a3bc4800]
2016-09-26 15:46:09.374556 UTC - [Main Thread]: V/nsHttp HttpBaseChannel::Init [this=7f64a3bc4800]
2016-09-26 15:46:09.374577 UTC - [Main Thread]: V/nsHttp host=localhost port=35255
2016-09-26 15:46:09.374580 UTC - [Main Thread]: V/nsHttp uri=http://localhost:35255/content
2016-09-26 15:46:09.374609 UTC - [Main Thread]: D/nsHttp nsHttpChannel::Init [this=7f64a3bc4800]
2016-09-26 15:46:09.374941 UTC - [Main Thread]: D/nsHttp nsHttpChannel::AsyncOpen [this=7f64a3bc4800]
2016-09-26 15:46:09.375023 UTC - [Main Thread]: V/nsHttp HttpBaseChannel::SetRequestHeader [this=7f64a3bc4800 header="Cookie" value="" merge=0]
2016-09-26 15:46:09.375033 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3bc4858 event="http-on-opening-request"]
2016-09-26 15:46:09.375042 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ResolveProxy [this=7f64a3bc4800]
2016-09-26 15:46:09.375094 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnProxyAvailable [this=7f64a3bc4800 pi=0 status=0 mStatus=0]
2016-09-26 15:46:09.375113 UTC - [Main Thread]: D/nsHttp nsHttpChannel::BeginConnect [this=7f64a3bc4800]
2016-09-26 15:46:09.375117 UTC - [Main Thread]: D/nsHttp host=localhost port=35255
2016-09-26 15:46:09.375119 UTC - [Main Thread]: D/nsHttp uri=http://localhost:35255/content
2016-09-26 15:46:09.375154 UTC - [Main Thread]: D/nsHttp AltSvcCache::LookupMapping 7f64ae47e1e8 http:localhost:35255:.
2016-09-26 15:46:09.375180 UTC - [Main Thread]: D/nsHttp AltSvcCache::LookupMapping 7f64ae47e1e8 MISS
2016-09-26 15:46:09.375185 UTC - [Main Thread]: D/nsHttp AltSvcCache::GetAltServiceMapping 7f64ae47e1e8 key=http:localhost:35255:. existing=0 validated=0 ttl=0
2016-09-26 15:46:09.375188 UTC - [Main Thread]: D/nsHttp nsHttpChannel 7f64a3bc4800 Using default connection info
2016-09-26 15:46:09.375192 UTC - [Main Thread]: V/nsHttp Init nsHttpConnectionInfo @7f64ae478f60
2016-09-26 15:46:09.375254 UTC - [Main Thread]: D/nsHttp nsHttpChannelAuthProvider::AddAuthorizationHeaders? [this=7f64a1c94800 channel=7f64a3bc4d38]
2016-09-26 15:46:09.375266 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::GetAuthEntryForPath [key=http://localhost:35255 path=/]
2016-09-26 15:46:09.375273 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3bc4858 event="http-on-modify-request"]
2016-09-26 15:46:09.375325 UTC - [Main Thread]: D/nsHttp nsHttpChannel::BeginConnect [this=7f64a3bc4800] prefetching
2016-09-26 15:46:09.375359 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ContinueBeginConnectWithResult [this=7f64a3bc4800]
2016-09-26 15:46:09.375365 UTC - [Main Thread]: D/nsHttp nsHttpChannel::Connect [this=7f64a3bc4800]
2016-09-26 15:46:09.375405 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::SpeculativeConnect [ci=......localhost:35255]
2016-09-26 15:46:09.375435 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OpenCacheEntry [this=7f64a3bc4800]
2016-09-26 15:46:09.375471 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnCacheEntryCheck enter [channel=7f64a3bc4800 entry=7f64a1c93c00]
2016-09-26 15:46:09.375487 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::ParseCachedOriginalHeader [this=7f64a1cf2580]
2016-09-26 15:46:09.375509 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgSpeculativeConnect [ci=......localhost:35255]
2016-09-26 15:46:09.375513 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::ParseCachedHead [this=7f64a1cf2580]
2016-09-26 15:46:09.375547 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::AtActiveConnectionLimit [ci=......localhost:35255 caps=401]
2016-09-26 15:46:09.375572 UTC - [Socket Thread]: V/nsHttp    connection count = 0, limit 6
2016-09-26 15:46:09.375574 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::ParseVersion [version=HTTP/1.1 200 OK
Content-Type: text/plain
Cache-Control: no-cache
Etag: test-etag1
Server: httpd.js
Date: Mon, 26 Sep 2016 15:46:09 GMT
Content-Length: 13
]
2016-09-26 15:46:09.375583 UTC - [Socket Thread]: V/nsHttp   result: false
2016-09-26 15:46:09.375584 UTC - [Main Thread]: D/nsHttp Have status line [version=11 status=200 statusText=OK]
2016-09-26 15:46:09.375595 UTC - [Socket Thread]: V/nsHttp Creating nsHalfOpenSocket [this=7f64a1cc57a0 trans=7f64a3b15b00 ent=localhost key=......localhost:35255]
2016-09-26 15:46:09.375601 UTC - [Main Thread]: D/nsHttp ParseContentType [type=text/plain]
2016-09-26 15:46:09.375615 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::SetupStreams [this=7f64a1cc57a0 ent=......localhost:35255] setup routed transport to origin localhost:35255 via :443
2016-09-26 15:46:09.375623 UTC - [Main Thread]: D/nsHttp ### CheckPartial rv=0, size=13, contentLength=13
2016-09-26 15:46:09.375632 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::MustValidate ??
2016-09-26 15:46:09.375635 UTC - [Main Thread]: D/nsHttp Must validate since response contains 'no-cache' header
2016-09-26 15:46:09.375638 UTC - [Main Thread]: D/nsHttp Validating based on MustValidate() returning TRUE
2016-09-26 15:46:09.375657 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ActivateTimeoutTick() this=7f64ae47e1e0 mTimeoutTick=7f64a1c8ada0
2016-09-26 15:46:09.375675 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::SetupPrimaryStream [this=7f64a1cc57a0 ent=localhost rv=0]
2016-09-26 15:46:09.375748 UTC - [Main Thread]: D/nsHttp Opened cache input stream without buffering [channel=7f64a3bc4800, mCacheEntry=7f64a1c93c00, stream=7f64a1c8be40]
2016-09-26 15:46:09.375749 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::SetupBackupTimer() [this=7f64a1cc57a0], did not arm
2016-09-26 15:46:09.375758 UTC - [Main Thread]: D/nsHttp nsHTTPChannel::OnCacheEntryCheck exit [this=7f64a3bc4800 doValidation=1 result=2]
2016-09-26 15:46:09.375829 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnCacheEntryAvailable [this=7f64a3bc4800 entry=7f64a3b10640 new=0 appcache=0 status=0 mAppCache=0 mAppCacheForWrite=0]
2016-09-26 15:46:09.375842 UTC - [Main Thread]: D/nsHttp nsHttpChannel::SetupTransaction [this=7f64a3bc4800]
2016-09-26 15:46:09.375854 UTC - [Main Thread]: D/nsHttp Creating nsHttpTransaction @7f64a1c9bc00
2016-09-26 15:46:09.375860 UTC - [Main Thread]: D/nsHttp nsHttpChannel 7f64a3bc4800 created nsHttpTransaction 7f64a1c9bc00
2016-09-26 15:46:09.375864 UTC - [Main Thread]: D/nsHttp nsHttpTransaction::Init [this=7f64a1c9bc00 caps=1]
2016-09-26 15:46:09.375902 UTC - [Main Thread]: I/nsHttp http request [
2016-09-26 15:46:09.375908 UTC - [Main Thread]: I/nsHttp   GET /content HTTP/1.1
2016-09-26 15:46:09.375911 UTC - [Main Thread]: I/nsHttp   Host: localhost:35255
2016-09-26 15:46:09.375915 UTC - [Main Thread]: I/nsHttp   User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:52.0) Gecko/20100101 /52.0a1
2016-09-26 15:46:09.375915 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::OnOutputStreamReady [this=7f64a1cc57a0 ent=localhost primary]
2016-09-26 15:46:09.375921 UTC - [Main Thread]: I/nsHttp   Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
2016-09-26 15:46:09.375948 UTC - [Main Thread]: I/nsHttp   Accept-Language: en-US,en;q=0.5
2016-09-26 15:46:09.375953 UTC - [Main Thread]: I/nsHttp   Accept-Encoding: gzip, deflate
2016-09-26 15:46:09.375956 UTC - [Main Thread]: I/nsHttp   Connection: keep-alive
2016-09-26 15:46:09.375956 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick armed=1 active=0
2016-09-26 15:46:09.375982 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick stop==true
2016-09-26 15:46:09.375984 UTC - [Main Thread]: I/nsHttp   If-None-Match: test-etag1
2016-09-26 15:46:09.375989 UTC - [Main Thread]: I/nsHttp ]
2016-09-26 15:46:09.376003 UTC - [Socket Thread]: V/nsHttp Creating nsHttpConnection @7f64a48d0200
2016-09-26 15:46:09.376010 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::OnOutputStreamReady Created new nshttpconnection 7f64a48d0200
2016-09-26 15:46:09.376012 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::AddTransaction [trans=7f64a1c9bc00 0]
2016-09-26 15:46:09.376037 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Init this=7f64a48d0200
2016-09-26 15:46:09.376073 UTC - [Socket Thread]: V/nsHttp nsHalfOpenSocket::OnOutputStreamReady no transaction match returning conn 7f64a48d0200 to pool
2016-09-26 15:46:09.376078 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ContinueBeginConnectWithResult result [this=7f64a3bc4800 rv=0 mCanceled=0]
2016-09-26 15:46:09.376079 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgReclaimConnection [conn=7f64a48d0238]
2016-09-26 15:46:09.376094 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d0200 caps=0x401 ......localhost:35255
2016-09-26 15:46:09.376103 UTC - [Socket Thread]: V/nsHttp   adding connection to idle list
2016-09-26 15:46:09.376110 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::BeginIdleMonitoring [this=7f64a48d0200]
2016-09-26 15:46:09.376115 UTC - [Socket Thread]: V/nsHttp Entering Idle Monitoring Mode [this=7f64a48d0200]
2016-09-26 15:46:09.376121 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::PruneDeadConnectionsAfter
2016-09-26 15:46:09.376137 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.376145 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=1 queued=0]
2016-09-26 15:46:09.376152 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=1 queued=0]
2016-09-26 15:46:09.376177 UTC - [Socket Thread]: V/nsHttp Destroying nsHalfOpenSocket [this=7f64a1cc57a0]
2016-09-26 15:46:09.376189 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.376232 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgNewTransaction [trans=7f64a1c9bc28]
2016-09-26 15:46:09.376241 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d0200 caps=0x401 ......localhost:35255
2016-09-26 15:46:09.376261 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::TryDispatchTransaction without conn [trans=7f64a1c9bc00 ci=7f64ae478e80 ci=......localhost:35255 caps=1 tunnelprovider=0 onlyreused=0 active=0 idle=1]
2016-09-26 15:46:09.376277 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d0200 caps=0x401 ......localhost:35255
2016-09-26 15:46:09.376284 UTC - [Socket Thread]: V/nsHttp    reusing connection [conn=7f64a48d0200]
2016-09-26 15:46:09.376289 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::EndIdleMonitoring [this=7f64a48d0200]
2016-09-26 15:46:09.376294 UTC - [Socket Thread]: V/nsHttp Leaving Idle Monitoring Mode [this=7f64a48d0200]
2016-09-26 15:46:09.376300 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::StopPruneDeadConnectionsTimer
2016-09-26 15:46:09.376310 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ActivateTimeoutTick() this=7f64ae47e1e0 mTimeoutTick=7f64a1c8ada0
2016-09-26 15:46:09.376345 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::DispatchTransaction [ent-ci=......localhost:35255 7f64a1c8b040 trans=7f64a1c9bc00 caps=1 conn=7f64a48d0200 priority=0]
2016-09-26 15:46:09.376366 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::DispatchAbstractTransaction [ci=......localhost:35255 trans=7f64a1c9bc00 caps=1 conn=7f64a48d0200]
2016-09-26 15:46:09.376370 UTC - [Socket Thread]: V/nsHttp    not using pipeline datastructure due to class solo.
2016-09-26 15:46:09.376378 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Activate [this=7f64a48d0200 trans=7f64a1c9bc00 caps=1]
2016-09-26 15:46:09.376395 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::SetupSSL 7f64a48d0200 caps=0x1 ......localhost:35255
2016-09-26 15:46:09.376403 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::StartShortLivedTCPKeepalives[7f64a48d0200] idle time[10s].
2016-09-26 15:46:09.376425 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketWritable [this=7f64a48d0200] host=localhost
2016-09-26 15:46:09.376432 UTC - [Socket Thread]: V/nsHttp   writing transaction request stream
2016-09-26 15:46:09.376451 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::GetSecurityInfo trans=7f64a1c9bc00 tlsfilter=0 socket=7f64a1c9b018
2016-09-26 15:46:09.376472 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9bc00 status=804b0005 progress=314]
2016-09-26 15:46:09.376478 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnTransportStatus 7f64a1c9bc00 SENDING_TO without request body
2016-09-26 15:46:09.376485 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketWritable 7f64a48d0200 ReadSegments returned [rv=0 read=314 sock-cond=0]
2016-09-26 15:46:09.376490 UTC - [Socket Thread]: V/nsHttp   writing transaction request stream
2016-09-26 15:46:09.376496 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketWritable 7f64a48d0200 ReadSegments returned [rv=0 read=0 sock-cond=0]
2016-09-26 15:46:09.376501 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9bc00 status=804b000a progress=0]
2016-09-26 15:46:09.376513 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::ResumeRecv [this=7f64a48d0200]
2016-09-26 15:46:09.376527 UTC - [Socket Thread]: V/nsHttp    dispatched step 2 (idle) trans=7f64a1c9bc00
2016-09-26 15:46:09.376533 UTC - [Socket Thread]: V/nsHttp   ProcessNewTransaction Dispatch Immediately trans=7f64a1c9bc00
2016-09-26 15:46:09.376542 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.376550 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=1 idle=0 queued=0]
2016-09-26 15:46:09.376557 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=1 idle=0 queued=0]
2016-09-26 15:46:09.377783 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnLookupComplete [this=7f64a3bc4800] prefetch complete: success status[0x0]
2016-09-26 15:46:09.429018 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable [this=7f64a48d0200]
2016-09-26 15:46:09.429042 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::WriteSegments 7f64a1c9bc00 reentrantFlag=0
2016-09-26 15:46:09.429101 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::OnSocketStatus [this=7f64a1c9bc00 status=804b0006 progress=191]
2016-09-26 15:46:09.429106 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ProcessData [this=7f64a1c9bc00 count=191]
2016-09-26 15:46:09.429109 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseHead [count=191]
2016-09-26 15:46:09.429120 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [HTTP/1.1 304 Not Modified]
2016-09-26 15:46:09.429124 UTC - [Socket Thread]: D/nsHttp nsHttpResponseHead::ParseVersion [version=HTTP/1.1 304 Not Modified]
2016-09-26 15:46:09.429130 UTC - [Socket Thread]: D/nsHttp Have status line [version=11 status=304 statusText=Not Modified]
2016-09-26 15:46:09.429142 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [content-type: text/plain]
2016-09-26 15:46:09.429155 UTC - [Socket Thread]: D/nsHttp ParseContentType [type=text/plain]
2016-09-26 15:46:09.429163 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [cache-control: no-cache]
2016-09-26 15:46:09.429171 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [etag: test-etag1]
2016-09-26 15:46:09.429178 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [connection: close]
2016-09-26 15:46:09.429186 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [server: httpd.js]
2016-09-26 15:46:09.429193 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [date: Mon, 26 Sep 2016 15:46:09 GMT]
2016-09-26 15:46:09.429199 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::ParseLine [content-length: 0]
2016-09-26 15:46:09.429208 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContent [this=7f64a1c9bc00 count=0]
2016-09-26 15:46:09.429212 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContentStart [this=7f64a1c9bc00]
2016-09-26 15:46:09.429214 UTC - [Socket Thread]: I/nsHttp http response [
2016-09-26 15:46:09.429232 UTC - [Socket Thread]: I/nsHttp   HTTP/1.1 304 Not Modified
2016-09-26 15:46:09.429236 UTC - [Socket Thread]: I/nsHttp   Content-Type: text/plain
2016-09-26 15:46:09.429239 UTC - [Socket Thread]: I/nsHttp   Cache-Control: no-cache
2016-09-26 15:46:09.429242 UTC - [Socket Thread]: I/nsHttp   Etag: test-etag1
2016-09-26 15:46:09.429245 UTC - [Socket Thread]: I/nsHttp   Connection: close
2016-09-26 15:46:09.429247 UTC - [Socket Thread]: I/nsHttp   Server: httpd.js
2016-09-26 15:46:09.429250 UTC - [Socket Thread]: I/nsHttp   Date: Mon, 26 Sep 2016 15:46:09 GMT
2016-09-26 15:46:09.429253 UTC - [Socket Thread]: I/nsHttp   Content-Length: 0
2016-09-26 15:46:09.429256 UTC - [Socket Thread]: I/nsHttp     OriginalHeaders
2016-09-26 15:46:09.429259 UTC - [Socket Thread]: I/nsHttp   Content-Type: text/plain
2016-09-26 15:46:09.429262 UTC - [Socket Thread]: I/nsHttp   Cache-Control: no-cache
2016-09-26 15:46:09.429264 UTC - [Socket Thread]: I/nsHttp   Etag: test-etag1
2016-09-26 15:46:09.429267 UTC - [Socket Thread]: I/nsHttp   Connection: close
2016-09-26 15:46:09.429269 UTC - [Socket Thread]: I/nsHttp   Server: httpd.js
2016-09-26 15:46:09.429272 UTC - [Socket Thread]: I/nsHttp   Date: Mon, 26 Sep 2016 15:46:09 GMT
2016-09-26 15:46:09.429275 UTC - [Socket Thread]: I/nsHttp   Content-Length: 0
2016-09-26 15:46:09.429277 UTC - [Socket Thread]: I/nsHttp ]
2016-09-26 15:46:09.429282 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnHeadersAvailable [this=7f64a48d0200 trans=7f64a1c9bc00 response-head=7f64a1cf3480]
2016-09-26 15:46:09.429313 UTC - [Socket Thread]: V/nsHttp Assessing red penalty to localhost class 3 for event 131075. Penalty now 0, throttle[3] = 500
2016-09-26 15:46:09.429318 UTC - [Socket Thread]: D/nsHttp this response should not contain a body.
2016-09-26 15:46:09.429324 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::HandleContent [this=7f64a1c9bc00 count=0 read=0 mContentRead=0 mContentLength=0]
2016-09-26 15:46:09.429328 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction 7f64a1c9bc00 request context set to null in ReleaseBlockingTransaction() - was 0
2016-09-26 15:46:09.429333 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable 7f64a48d0200 trans->ws rv=0 n=0 socketin=0
2016-09-26 15:46:09.429336 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::WriteSegments 7f64a1c9bc00 reentrantFlag=0
2016-09-26 15:46:09.429340 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::OnSocketReadable 7f64a48d0200 trans->ws rv=80470002 n=0 socketin=0
2016-09-26 15:46:09.429344 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::CloseTransaction[this=7f64a48d0200 trans=7f64a1c9bc00 reason=80470002]
2016-09-26 15:46:09.429347 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction::Close [this=7f64a1c9bc00 reason=0]
2016-09-26 15:46:09.429353 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ReclaimConnection [conn=7f64a48d0200]
2016-09-26 15:46:09.429445 UTC - [Socket Thread]: D/nsHttp nsHttpTransaction 7f64a1c9bc00 request context set to null in ReleaseBlockingTransaction() - was 0
2016-09-26 15:46:09.429484 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgReclaimConnection [conn=7f64a48d0238]
2016-09-26 15:46:09.429494 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick armed=1 active=0
2016-09-26 15:46:09.429499 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ConditionallyStopTimeoutTick stop==true
2016-09-26 15:46:09.429511 UTC - [Socket Thread]: V/nsHttp   connection cannot be reused; closing connection
2016-09-26 15:46:09.429522 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Close [this=7f64a48d0200 reason=80004004]
2016-09-26 15:46:09.429560 UTC - [Socket Thread]: V/nsHttp nsHttpConnection::Close drained 0 bytes
2016-09-26 15:46:09.429577 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgProcessPendingQ [ci=......localhost:35255]
2016-09-26 15:46:09.429586 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=0 queued=0]
2016-09-26 15:46:09.429594 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::ProcessPendingQForEntry [ci=......localhost:35255 ent=7f64a1c8b040 active=0 idle=0 queued=0]
2016-09-26 15:46:09.429600 UTC - [Socket Thread]: V/nsHttp Destroying nsHttpConnection @7f64a48d0200
2016-09-26 15:46:09.429606 UTC - [Socket Thread]: V/nsHttp nsHttpConnection 7f64a48d0200 performed 1 HTTP/1.x transactions
2016-09-26 15:46:09.430837 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnStartRequest [this=7f64a3bc4800 request=7f64a3b0c3c0 status=0]
2016-09-26 15:46:09.430851 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ProcessResponse [this=7f64a3bc4800 httpStatus=304]
2016-09-26 15:46:09.430860 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3bc4858 event="http-on-examine-response"]
2016-09-26 15:46:09.430892 UTC - [Main Thread]: D/nsHttp nsHttpChannelAuthProvider::CheckForSuperfluousAuth? [this=7f64a1c94800 channel=7f64a3bc4d38]
2016-09-26 15:46:09.430899 UTC - [Main Thread]: D/nsHttp   continuation state has been reset
2016-09-26 15:46:09.430903 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ProcessNotModified [this=7f64a3bc4800]
2016-09-26 15:46:09.430908 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::UpdateHeaders [this=7f64a1cf2580]
2016-09-26 15:46:09.430915 UTC - [Main Thread]: D/nsHttp ignoring response header [Content-Type: text/plain]
2016-09-26 15:46:09.430918 UTC - [Main Thread]: D/nsHttp new response header [Cache-Control: no-cache]
2016-09-26 15:46:09.430924 UTC - [Main Thread]: D/nsHttp ignoring response header [Etag: test-etag1]
2016-09-26 15:46:09.430927 UTC - [Main Thread]: D/nsHttp ignoring response header [Connection: close]
2016-09-26 15:46:09.430930 UTC - [Main Thread]: D/nsHttp new response header [Server: httpd.js]
2016-09-26 15:46:09.430934 UTC - [Main Thread]: D/nsHttp new response header [Date: Mon, 26 Sep 2016 15:46:09 GMT]
2016-09-26 15:46:09.430938 UTC - [Main Thread]: D/nsHttp ignoring response header [Content-Length: 0]
2016-09-26 15:46:09.431008 UTC - [Main Thread]: D/nsHttp nsHttpResponseHead::MustValidate ??
2016-09-26 15:46:09.431032 UTC - [Main Thread]: D/nsHttp Must validate since response contains 'no-cache' header
2016-09-26 15:46:09.431048 UTC - [Main Thread]: D/nsHttp nsHttpChannel::AddCacheEntryHeaders [this=7f64a3bc4800] begin
2016-09-26 15:46:09.431142 UTC - [Main Thread]: D/nsHttp nsHttpHandler::NotifyObservers [chan=a3bc4858 event="http-on-examine-merged-response"]
2016-09-26 15:46:09.431169 UTC - [Main Thread]: D/nsHttp nsHttpChannel::ReadFromCache [this=7f64a3bc4800] Using cached copy of: http://localhost:35255/content
2016-09-26 15:46:09.431241 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnStopRequest [this=7f64a3bc4800 request=7f64a3b0c3c0 status=0]
2016-09-26 15:46:09.431245 UTC - [Main Thread]: D/nsHttp   dropping upload stream
2016-09-26 15:46:09.431252 UTC - [Main Thread]: D/nsHttp nsHttpChannel 7f64a3bc4800 has a strongly framed transaction: 1
2016-09-26 15:46:09.431269 UTC - [Main Thread]: D/nsHttp nsHttpTransaction::DeleteSelfOnConsumerThread [this=7f64a1c9bc00]
2016-09-26 15:46:09.431273 UTC - [Main Thread]: D/nsHttp Destroying nsHttpTransaction @7f64a1c9bc00
2016-09-26 15:46:09.431277 UTC - [Main Thread]: D/nsHttp nsHttpTransaction 7f64a1c9bc00 request context set to null in ReleaseBlockingTransaction() - was 0
2016-09-26 15:46:09.491501 UTC - [Main Thread]: D/nsHttp Destroying nsHttpChannel [this=7f64a3b57000]
2016-09-26 15:46:09.491541 UTC - [Main Thread]: V/nsHttp Destroying HttpBaseChannel @a3b57000
2016-09-26 15:46:09.491564 UTC - [Main Thread]: D/nsHttp Destroying nsHttpConnectionInfo @ae478be0
2016-09-26 15:46:09.492010 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnStartRequest [this=7f64a3bc4800 request=7f64a3b0d800 status=0]
2016-09-26 15:46:09.492037 UTC - [Main Thread]: D/nsHttp   calling mListener->OnStartRequest
2016-09-26 15:46:09.492253 UTC - [Main Thread]: V/nsHttp HttpBaseChannel::DoApplyContentConversions [this=7f64a3bc4800]
2016-09-26 15:46:09.492269 UTC - [Main Thread]: V/nsHttp not applying conversion because delivering alt-data
2016-09-26 15:46:09.492298 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnDataAvailable [this=7f64a3bc4800 request=7f64a3b0d800 offset=0 count=262147]
2016-09-26 15:46:09.505078 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnDataAvailable [this=7f64a3bc4800 request=7f64a3b0d800 offset=262147 count=786442]
2016-09-26 15:46:09.508103 UTC - [Main Thread]: D/nsHttp nsHttpChannel::OnStopRequest [this=7f64a3bc4800 request=7f64a3b0d800 status=0]
2016-09-26 15:46:09.508119 UTC - [Main Thread]: D/nsHttp   dropping upload stream
2016-09-26 15:46:09.508128 UTC - [Main Thread]: D/nsHttp nsHttpChannel::FinalizeCacheEntry [this=7f64a3bc4800]
2016-09-26 15:46:09.508133 UTC - [Main Thread]: D/nsHttp   calling OnStopRequest
2016-09-26 15:46:09.623811 UTC - [Main Thread]: D/nsHttp nsHttpChannel::CloseCacheEntry [this=7f64a3bc4800] mStatus=0 mCacheEntryIsWriteOnly=0
2016-09-26 15:46:09.632574 UTC - [Main Thread]: D/nsHttp nsHttpHandler::Observe [topic="profile-change-net-teardown"]
2016-09-26 15:46:09.632588 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::ClearAll
2016-09-26 15:46:09.632595 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::ClearAll
2016-09-26 15:46:09.632599 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::Shutdown
2016-09-26 15:46:09.632713 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgShutdown
2016-09-26 15:46:09.632730 UTC - [Socket Thread]: V/nsHttp nsHttpConnectionMgr::StopPruneDeadConnectionsTimer
2016-09-26 15:46:09.632737 UTC - [Socket Thread]: D/nsHttp Destroying nsHttpConnectionInfo @ae478e80
2016-09-26 15:46:09.632760 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::OnMsgShutdownConfirm
2016-09-26 15:46:09.711097 UTC - [Main Thread]: D/nsHttp Destroying nsHttpChannel [this=7f64a3bc4800]
2016-09-26 15:46:09.711147 UTC - [Main Thread]: V/nsHttp Destroying HttpBaseChannel @a3bc4800
2016-09-26 15:46:09.711163 UTC - [Main Thread]: D/nsHttp Destroying nsHttpConnectionInfo @ae478f60
2016-09-26 15:46:09.771425 UTC - [Main Thread]: D/nsHttp nsHttpHandler::Observe [topic="xpcom-shutdown"]
2016-09-26 15:46:09.771444 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::ClearAll
2016-09-26 15:46:09.771465 UTC - [Main Thread]: D/nsHttp nsHttpAuthCache::ClearAll
2016-09-26 15:46:09.771488 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::Shutdown
2016-09-26 15:46:09.773962 UTC - [Main Thread]: D/nsHttp Deleting nsHttpHandler [this=7f64a3b87000]
2016-09-26 15:46:09.773974 UTC - [Main Thread]: V/nsHttp nsHttpConnectionMgr::Shutdown
2016-09-26 15:46:09.773981 UTC - [Main Thread]: V/nsHttp Destroying nsHttpConnectionMgr @7f64ae47e1e0