Skip to content

Instantly share code, notes, and snippets.

@imechemi
Created July 11, 2017 04:25
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save imechemi/adfa410bc34bcf764a09e8aaf8fdbdfe to your computer and use it in GitHub Desktop.
Save imechemi/adfa410bc34bcf764a09e8aaf8fdbdfe to your computer and use it in GitHub Desktop.
This is the actual error log with PassengerLogLevel 7 and during incident time.
[ 2017-07-09 08:55:01.1282 6743/7fb4a7f34700 Uti/ScopeGuard.h:113 ]: File descriptor closed: 66
[ 2017-07-09 08:55:01.1284 6743/7fb4a7f34700 App/Poo/AnalyticsCollection.cpp:71 ]: Analytics collection done; next analytics collection in 4.872 sec
[ 2017-07-09 08:55:02.0688 6743/7fb476bfd700 Ser/Server.h:241 ]: [ApiServer] New clients can be accepted on a server socket
[ 2017-07-09 08:55:02.0690 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.0690 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2041] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.0690 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2041
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/Server.h:267 ]: [ApiServer] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2041] Event: onClientAccepted
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2041] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2041] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.0691 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.0692 6743/7fb476bfd700 Ser/Server.h:452 ]: [Client A2041] Processing 206 bytes of client data
[ 2017-07-09 08:55:02.0693 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2041] Event: onClientDataReceived
[ 2017-07-09 08:55:02.0693 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2041] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.0693 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2041] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:02.0694 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2041] New request received: #1
[ 2017-07-09 08:55:02.0694 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.0695 6743/7fb476bfd700 age/ApiServerUtils.h:252 ]: [Client A2041] Authenticated with UID: 0
[ 2017-07-09 08:55:02.0695 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2041] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.0695 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2041] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:02.0698 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 146 bytes
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.0699 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.0700 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:02.0700 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0700 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 2582
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 2582 bytes
[ 2017-07-09 08:55:02.0701 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2582 bytes
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2041] Ending request
[ 2017-07-09 08:55:02.0702 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2041] Ending request
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.0703 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2041] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2041] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2041] Refcount increased; it is now 4
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2041] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2041] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2041] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2041] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2041] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.0706 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2041] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2041] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2041] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2041] Refcount increased; it is now 4
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.0704 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2041] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2041] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2041] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2041] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.0705 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2041] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.0706 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2041] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.0706 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2041] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.0706 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2041] Client sent EOF
[ 2017-07-09 08:55:02.0706 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2041] Client sent EOF
[ 2017-07-09 08:55:02.0706 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2041] Event: onClientDataReceived
[ 2017-07-09 08:55:02.0707 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2041] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.0707 6743/7fb476bfd700 Ser/Server.h:896 ]: [Client A2041] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.0707 6743/7fb476bfd700 Ser/HttpServer.h:725 ]: [Client A2041] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.0707 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.0707 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2041] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.0707 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2041] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2041] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2041] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2041] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2041] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2041] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.0708 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2041] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.0709 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2041] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.0709 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2041] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.0709 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2041] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.0709 6743/7fb476bfd700 Ser/Server.h:378 ]: [Client A2041] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.0709 6743/7fb476bfd700 Ser/Server.h:387 ]: [Client A2041] Client object destroyed; not added to freelist because it's full (0)
[ 2017-07-09 08:55:02.0764 6751/7f5ab40b0700 age/Ust/LoggingServer.h:753 ]: Flushing all sinks
[ 2017-07-09 08:55:02.1010 6779/7f65892e27e0 apa/Hooks.cpp:275 ]: File descriptor opened: 10
[ 2017-07-09 08:55:02.1012 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: 66
[ 2017-07-09 08:55:02.1012 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:135 ]: Accepted client file descriptor: 66
[ 2017-07-09 08:55:02.1012 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: -1
[ 2017-07-09 08:55:02.1012 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: -1
[ 2017-07-09 08:55:02.1013 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:163 ]: Feeding client to server thread 3: file descriptor 66
[ 2017-07-09 08:55:02.1014 6743/7fb4a5375700 age/Cor/Req/TurboCaching.h:242 ]: Clearing turbocache
[ 2017-07-09 08:55:02.1015 6743/7fb4a5375700 Ser/Server.h:352 ]: [ServerThr.4] Checking out client object from freelist (128 -> 127)
[ 2017-07-09 08:55:02.1015 6743/7fb4a5375700 Ser/Server.h:352 ]: [ServerThr.4] Checking out client object from freelist (128 -> 127)
[ 2017-07-09 08:55:02.1015 6743/7fb4a5375700 Ser/Server.h:575 ]: [Client 4-4816] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.1015 6743/7fb4a5375700 Ser/Server.h:575 ]: [Client 4-4816] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.1016 6743/7fb4a5375700 Ser/Server.h:726 ]: File descriptor purpose: 66: Server ServerThr.4, client 4-4816
[ 2017-07-09 08:55:02.1016 6743/7fb4a5375700 Ser/Server.h:730 ]: [ServerThr.4] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.1017 6743/7fb4a5375700 Ser/HttpServer.h:668 ]: [Client 4-4816] Event: onClientAccepted
[ 2017-07-09 08:55:02.1017 6743/7fb4a5375700 Ser/HttpServer.h:235 ]: [Client 4-4816] Refcount increased; it is now 3
ased; it is now 3
[ 2017-07-09 08:55:02.1018 6743/7fb4a5375700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xb5de10] Deinitialize
[ 2017-07-09 08:55:02.1018 6743/7fb4a5375700 Ser/HttpServer.h:119 ]: [ServerThr.4] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.1018 6743/7fb4a5375700 Ser/Server.h:452 ]: [Client 4-4816] Processing 464 bytes of client data
[ 2017-07-09 08:55:02.1019 6743/7fb4a5375700 Ser/HttpServer.h:676 ]: [Client 4-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.1019 6743/7fb4a5375700 Ser/HttpServer.h:679 ]: [Client 4-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.1019 6743/7fb4a5375700 Ser/HttpServer.h:679 ]: [Client 4-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.1019 6743/7fb4a5375700 Ser/HttpServer.h:256 ]: [Client 4-4816] Parsing 464 bytes of HTTP header: "HEAD /client-solution-configuration-service/meta/availability HTTP/1.1\r\nX-Forwarded-Proto: https\r\nConnection: close\r\n!~: kbdnBq92lKPPhbOsZhFlmKsr\r\n!~DOCUMENT_ROOT: /opt/healtheintent/applications/client_solution_configuration_service/public\r\n!~SCRIPT_NAME: /client-solution-configuration-service\r\n!~REMOTE_ADDR: 10.190.115.27\r\n!~REMOTE_PORT: 53316\r\n!~PASSENGER_APP_GROUP_NAME: /opt/healtheintent/applications/client_solution_configuration_service\r\n!~PASSENGER_APP_"
[ 2017-07-09 08:55:02.1020 6743/7fb4a5375700 Ser/HttpServer.h:679 ]: [Client 4-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1021 6743/7fb4a5375700 Ser/Server.h:479 ]: [Client 4-4816] 464 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.1021 6743/7fb4a5375700 Ser/Server.h:528 ]: [Client 4-4816] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.1021 6743/7fb4a5375700 Ser/FdSourceChannel.h:54 ]: [Client 4-4816] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.1022 6743/7fb4a5375700 Ser/Server.h:452 ]: [Client 4-4816] Processing 265 bytes of client data
[ 2017-07-09 08:55:02.1022 6743/7fb4a5375700 Ser/HttpServer.h:676 ]: [Client 4-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.1023 6743/7fb4a5375700 Ser/HttpServer.h:679 ]: [Client 4-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.1023 6743/7fb4a5375700 Ser/HttpServer.h:256 ]: [Client 4-4816] Parsing 265 bytes of HTTP header: "ROOT: /opt/healtheintent/applications/client_solution_configuration_service\r\n!~PASSENGER_APP_TYPE: rack\r\n!~PASSENGER_RUBY: /usr/local/ruby/2.2.3/bin/ruby\r\n!~PASSENGER_MIN_PROCESSES: 18\r\n!~PASSENGER_MAX_REQUESTS: 30\r\n!~PASSENGER_SPAWN_METHOD: smart\r\n!~FLAGS: CDB\r\n\r\n"
[ 2017-07-09 08:55:02.1023 6743/7fb4a5375700 Ser/HttpServer.h:267 ]: [Client 4-4816] New request received: #4816
[ 2017-07-09 08:55:02.1024 6743/7fb4a5375700 age/Cor/Req/InitRequest.cpp:59 ]: [Client 4-4816] Initiating request
[ 2017-07-09 08:55:02.1024 6743/7fb4a5375700 age/Cor/Req/InitRequest.cpp:124 ]: [Client 4-4816] Dechunk flag detected
[ 2017-07-09 08:55:02.1024 6743/7fb4a5375700 age/Cor/Req/InitRequest.cpp:127 ]: [Client 4-4816] Request body buffering enabled
[ 2017-07-09 08:55:02.1024 6743/7fb4a5375700 age/Cor/Req/InitRequest.cpp:133 ]: [Client 4-4816] Stripping 100 Continue header
[ 2017-07-09 08:55:02.1025 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:36 ]: [Client 4-4816] Checking out session: appRoot=/opt/healtheintent/applications/client_solution_configuration_service
[ 2017-07-09 08:55:02.1024 6743/7fb4a5375700 age/Cor/Req/InitRequest.cpp:133 ]: [Client 4-4816] Stripping 100 Continue header
[ 2017-07-09 08:55:02.1025 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:36 ]: [Client 4-4816] Checking out session: appRoot=/opt/healtheintent/applications/client_solution_configuration_service
[ 2017-07-09 08:55:02.1025 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:50 ]: [Client 4-4816] Request refcount increased; it is now 3
[ 2017-07-09 08:55:02.1025 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:50 ]: [Client 4-4816] Request refcount increased; it is now 3
[ 2017-07-09 08:55:02.1025 6743/7fb4a5375700 App/Poo/Miscellaneous.cpp:42 ]: asyncGet(appGroupName=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.1025 6743/7fb4a5375700 App/Poo/Miscellaneous.cpp:42 ]: asyncGet(appGroupName=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.1026 6743/7fb4a5375700 App/Poo/Miscellaneous.cpp:48 ]: Found existing Group
[ 2017-07-09 08:55:02.1026 6743/7fb4a5375700 App/Gro/SessionManagement.cpp:329 ]: Session checked out from process (pid=7603, group=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.1027 6743/7fb4a5375700 App/Poo/Miscellaneous.cpp:53 ]: asyncGet() finished
[ 2017-07-09 08:55:02.1027 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:114 ]: [Client 4-4816] Session checked out: pid=7603, gupid=17d5ebd-NxAAcIkqYu
[ 2017-07-09 08:55:02.1027 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:114 ]: [Client 4-4816] Session checked out: pid=7603, gupid=17d5ebd-NxAAcIkqYu
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 App/Socket.h:167 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking out connection from connection pool (1 -> 0 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 App/Socket.h:167 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking out connection from connection pool (1 -> 0 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:182 ]: [Client 4-4816] Session initiated: fd=81
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 age/Cor/Req/SendRequest.cpp:34 ]: [Client 4-4816] Sending headers to application with session protocol
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 age/Cor/Req/SendRequest.cpp:124 ]: [Client 4-4816] Header data: "\000\000\001\250REQUEST_URI\000/client-solution-configuration-service/meta/availability\000PATH_INFO\000/client-solution-configuration-service/meta/availability\000SCRIPT_NAME\000\000QUERY_STRING\000\000REQUEST_METHOD\000HEAD\000SERVER_NAME\000localhost\000SERVER_PORT\00080\000SERVER_SOFTWARE\000Apache/2.2.15 (Unix) Phusion_Passenger/5.0.18\000SERVER_PROTOCOL\000HTTP/1.1\000REMOTE_ADDR\00010.190.115.27\000REMOTE_PORT\00053316\000PASSENGER_CONNECT_PASSWORD\000W42m1JWqR42WXZSN\000HTTP_X_FORWARDED_PROTO\000https\000"
[ 2017-07-09 08:55:02.1029 6743/7fb4a5375700 age/Cor/Req/SendRequest.cpp:846 ]: [Client 4-4816] No body to send to application
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:75 ]: [Client 4-4816] Request refcount decreased; it is now 2
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 Ser/HttpServer.h:679 ]: [Client 4-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 Ser/Server.h:479 ]: [Client 4-4816] 265 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 Ser/FdSourceChannel.h:54 ]: [Client 4-4816] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 age/Cor/Req/SendRequest.cpp:34 ]: [Client 4-4816] Sending headers to application with session protocol
[ 2017-07-09 08:55:02.1028 6743/7fb4a5375700 age/Cor/Req/SendRequest.cpp:124 ]: [Client 4-4816] Header data: "\000\000\001\250REQUEST_URI\000/client-solution-configuration-service/meta/availability\000PATH_INFO\000/client-solution-configuration-service/meta/availability\000SCRIPT_NAME\000\000QUERY_STRING\000\000REQUEST_METHOD\000HEAD\000SERVER_NAME\000localhost\000SERVER_PORT\00080\000SERVER_SOFTWARE\000Apache/2.2.15 (Unix) Phusion_Passenger/5.0.18\000SERVER_PROTOCOL\000HTTP/1.1\000REMOTE_ADDR\00010.190.115.27\000REMOTE_PORT\00053316\000PASSENGER_CONNECT_PASSWORD\000W42m1JWqR42WXZSN\000HTTP_X_FORWARDED_PROTO\000https\000"
[ 2017-07-09 08:55:02.1029 6743/7fb4a5375700 age/Cor/Req/SendRequest.cpp:846 ]: [Client 4-4816] No body to send to application
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 age/Cor/Req/CheckoutSession.cpp:75 ]: [Client 4-4816] Request refcount decreased; it is now 2
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 Ser/HttpServer.h:679 ]: [Client 4-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 Ser/Server.h:479 ]: [Client 4-4816] 265 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.1030 6743/7fb4a5375700 Ser/FdSourceChannel.h:54 ]: [Client 4-4816] Refcount decreased; it is now 2
App 6794 stderr: [ 2017-07-09 08:55:02.1033 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:139 ]: Accepted new request on socket main socket
[ 2017-07-09 08:55:02.1118 6743/7fb4a5375700 Ser/FdSourceChannel.h:54 ]: [Client 4-4816] Request refcount increased; it is now 2
App 6794 stderr: [ 2017-07-09 08:55:02.1118 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:404 ]: Keep-aliving connection.
[ 2017-07-09 08:55:02.1119 6743/7fb4a5375700 age/Cor/Req/ForwardResponse.cpp:43 ]: [Client 4-4816] Event: onAppSourceData
[ 2017-07-09 08:55:02.1120 6743/7fb4a5375700 age/Cor/Req/ForwardResponse.cpp:54 ]: [Client 4-4816] Processing 157 bytes of application data: "HTTP/1.1 404 Whatever\r\nContent-Type: text/html; charset=utf-8\r\nContent-Length: 0\r\nX-Request-Id: 3860a442-f72e-419d-80b0-fb86465aef2e\r\nX-Runtime: 0.007981\r\n\r\n"
[ 2017-07-09 08:55:02.1120 6743/7fb4a72fb700 age/Cor/Req/TurboCaching.h:242 ]: Clearing turbocache
App 6794 stderr: [ 2017-07-09 08:55:02.1120 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:169 ]: Request done.
[ 2017-07-09 08:55:02.1121 6743/7fb4a5375700 age/Cor/Req/ForwardResponse.cpp:66 ]: [Client 4-4816] Application response headers received
[ 2017-07-09 08:55:02.1121 6743/7fb4a5375700 age/Cor/Req/ForwardResponse.cpp:800 ]: [Client 4-4816] Sending response headers using writev()
[ 2017-07-09 08:55:02.1121 6743/7fb4a5375700 age/Cor/Req/ForwardResponse.cpp:861 ]: [Client 4-4816] Sending response headers: "HTTP/1.1 404 Not Found\r\nStatus: 404 Not Found\r\nContent-Length: 0\r\nX-Runtime: 0.007981\r\nContent-Type: text/html; charset=utf-8\r\nX-Request-Id: 3860a442-f72e-419d-80b0-fb86465aef2e\r\nDate: Sun, 09 Jul 2017 13:55:02 GMT\r\nConnection: close\r\nX-Powered-By: Phusion Passenger 5.0.18\r\n\r\n"
[ 2017-07-09 08:55:02.1122 6743/7fb4a5375700 App/Socket.h:196 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking in connection into connection pool (0 -> 1 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.1123 6743/7fb4a5375700 App/Gro/SessionManagement.cpp:149 ]: Session closed for process (pid=7603, group=/opt/healtheintent/applications/client_solution_configuration_service)
olution_configuration_service)
[ 2017-07-09 08:55:02.1123 6743/7fb4a5375700 Ser/HttpServer.h:1060 ]: [Client 4-4816] Ending request
[ 2017-07-09 08:55:02.1124 6743/7fb4a5375700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb478006d10] Deinitialize
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0xb5de10] Feeding 0 bytes
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0xb5de10] Feeding 0 bytes
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:484 ]: [FBC 0xb5de10] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:484 ]: [FBC 0xb5de10] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:552 ]: [FBC 0xb5de10] Reader: reading next
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:552 ]: [FBC 0xb5de10] Reader: reading next
[ 2017-07-09 08:55:02.1125 6743/7fb4a5375700 Ser/FileBufferedChannel.h:564 ]: [FBC 0xb5de10] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.1126 6743/7fb4a5375700 Ser/FileBufferedChannel.h:580 ]: [FBC 0xb5de10] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.1126 6743/7fb4a5375700 Ser/FileBufferedChannel.h:537 ]: [FBC 0xb5de10] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.1127 6743/7fb4a5375700 Ser/HttpServer.h:219 ]: [Client 4-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1127 6743/7fb4a5375700 Ser/HttpServer.h:219 ]: [Client 4-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1127 6743/7fb4a5375700 Ser/HttpServer.h:224 ]: [Client 4-4816] Not keeping alive connection, disconnecting client
[ 2017-07-09 08:55:02.1127 6743/7fb4a5375700 Ser/Server.h:896 ]: [Client 4-4816] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.1127 6743/7fb4a5375700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xb5de10] Deinitialize
[ 2017-07-09 08:55:02.1128 6743/7fb4a5375700 Ser/Server.h:906 ]: [Client 4-4816] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.1128 6743/7fb4a5375700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.1129 6743/7fb4a5375700 Ser/Server.h:917 ]: [Client 4-4816] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1129 6743/7fb4a5375700 Ser/FdSourceChannel.h:54 ]: [Client 4-4816] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.1129 6743/7fb4a5375700 Ser/HttpServer.h:146 ]: [Client 4-4816] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.1129 6743/7fb4a5375700 Ser/HttpServer.h:146 ]: [Client 4-4816] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.1129 6743/7fb4a5375700 Ser/HttpServer.h:154 ]: [Client 4-4816] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.1129 6743/7fb4a5375700 Ser/HttpServer.h:161 ]: [Client 4-4816] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.1129 6779/7f65892e27e0 FileDescriptor.h:83 ]: File descriptor closed: 10
[ 2017-07-09 08:55:02.1130 6743/7fb4a5375700 Ser/Server.h:378 ]: [Client 4-4816] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.1129 6779/7f65892e27e0 FileDescriptor.h:83 ]: File descriptor closed: 10
[ 2017-07-09 08:55:02.1130 6743/7fb4a5375700 Ser/Server.h:378 ]: [Client 4-4816] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.1130 6743/7fb4a5375700 Ser/Server.h:384 ]: [Client 4-4816] Client object added to freelist (127 -> 128)
[ 2017-07-09 08:55:02.1257 6792/7f65892e27e0 apa/Hooks.cpp:275 ]: File descriptor opened: 10
[ 2017-07-09 08:55:02.1260 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: 66
[ 2017-07-09 08:55:02.1262 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:135 ]: Accepted client file descriptor: 66
[ 2017-07-09 08:55:02.1263 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: -1
[ 2017-07-09 08:55:02.1263 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:163 ]: Feeding client to server thread 4: file descriptor 66
[ 2017-07-09 08:55:02.1263 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: -1
[ 2017-07-09 08:55:02.1263 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:163 ]: Feeding client to server thread 4: file descriptor 66
[ 2017-07-09 08:55:02.1264 6743/7fb477fff700 age/Cor/Req/TurboCaching.h:242 ]: Clearing turbocache
[ 2017-07-09 08:55:02.1265 6743/7fb477fff700 Ser/Server.h:352 ]: [ServerThr.5] Checking out client object from freelist (128 -> 127)
[ 2017-07-09 08:55:02.1265 6743/7fb477fff700 Ser/Server.h:575 ]: [Client 5-4816] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.1266 6743/7fb477fff700 Ser/Server.h:726 ]: File descriptor purpose: 66: Server ServerThr.5, client 5-4816
[ 2017-07-09 08:55:02.1266 6743/7fb477fff700 Ser/Server.h:730 ]: [ServerThr.5] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.1266 6743/7fb477fff700 Ser/Server.h:730 ]: [ServerThr.5] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.1267 6743/7fb477fff700 Ser/HttpServer.h:668 ]: [Client 5-4816] Event: onClientAccepted
[ 2017-07-09 08:55:02.1267 6743/7fb477fff700 Ser/HttpServer.h:235 ]: [Client 5-4816] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.1267 6743/7fb477fff700 Ser/HttpServer.h:235 ]: [Client 5-4816] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.1267 6743/7fb477fff700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xb81610] Deinitialize
6743/7fb477fff700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xb81610] Deinitialize
[ 2017-07-09 08:55:02.1268 6743/7fb477fff700 Ser/HttpServer.h:119 ]: [ServerThr.5] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.1268 6743/7fb477fff700 Ser/HttpServer.h:119 ]: [ServerThr.5] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.1268 6743/7fb477fff700 Ser/Server.h:452 ]: [Client 5-4816] Processing 464 bytes of client data
[ 2017-07-09 08:55:02.1269 6743/7fb477fff700 Ser/HttpServer.h:676 ]: [Client 5-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.1269 6743/7fb477fff700 Ser/HttpServer.h:679 ]: [Client 5-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.1269 6743/7fb477fff700 Ser/HttpServer.h:256 ]: [Client 5-4816] Parsing 464 bytes of HTTP header: "HEAD /client-solution-configuration-service/meta/availability HTTP/1.1\r\nX-Forwarded-Proto: https\r\nConnection: close\r\n!~: kbdnBq92lKPPhbOsZhFlmKsr\r\n!~DOCUMENT_ROOT: /opt/healtheintent/applications/client_solution_configuration_service/public\r\n!~SCRIPT_NAME: /client-solution-configuration-service\r\n!~REMOTE_ADDR: 10.190.115.28\r\n!~REMOTE_PORT: 50852\r\n!~PASSENGER_APP_GROUP_NAME: /opt/healtheintent/applications/client_solution_configuration_service\r\n!~PASSENGER_APP_"
[ 2017-07-09 08:55:02.1270 6743/7fb477fff700 Ser/HttpServer.h:679 ]: [Client 5-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1271 6743/7fb477fff700 Ser/Server.h:479 ]: [Client 5-4816] 464 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.1271 6743/7fb477fff700 Ser/Server.h:479 ]: [Client 5-4816] 464 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.1271 6743/7fb477fff700 Ser/Server.h:528 ]: [Client 5-4816] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.1271 6743/7fb477fff700 Ser/FdSourceChannel.h:54 ]: [Client 5-4816] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.1272 6743/7fb477fff700 Ser/Server.h:452 ]: [Client 5-4816] Processing 265 bytes of client data
[ 2017-07-09 08:55:02.1272 6743/7fb477fff700 Ser/HttpServer.h:676 ]: [Client 5-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.1273 6743/7fb477fff700 Ser/HttpServer.h:679 ]: [Client 5-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.1273 6743/7fb477fff700 Ser/HttpServer.h:256 ]: [Client 5-4816] Parsing 265 bytes of HTTP header: "ROOT: /opt/healtheintent/applications/client_solution_configuration_service\r\n!~PASSENGER_APP_TYPE: rack\r\n!~PASSENGER_RUBY: /usr/local/ruby/2.2.3/bin/ruby\r\n!~PASSENGER_MIN_PROCESSES: 18\r\n!~PASSENGER_MAX_REQUESTS: 30\r\n!~PASSENGER_SPAWN_METHOD: smart\r\n!~FLAGS: CDB\r\n\r\n"
[ 2017-07-09 08:55:02.1274 6743/7fb477fff700 Ser/HttpServer.h:267 ]: [Client 5-4816] New request received: #4816
[ 2017-07-09 08:55:02.1274 6743/7fb477fff700 age/Cor/Req/InitRequest.cpp:59 ]: [Client 5-4816] Initiating request
[ 2017-07-09 08:55:02.1274 6743/7fb477fff700 age/Cor/Req/InitRequest.cpp:124 ]: [Client 5-4816] Dechunk flag detected
[ 2017-07-09 08:55:02.1274 6743/7fb477fff700 age/Cor/Req/InitRequest.cpp:127 ]: [Client 5-4816] Request body buffering enabled
[ 2017-07-09 08:55:02.1274 6743/7fb477fff700 age/Cor/Req/InitRequest.cpp:133 ]: [Client 5-4816] Stripping 100 Continue header
[ 2017-07-09 08:55:02.1275 6743/7fb477fff700 age/Cor/Req/CheckoutSession.cpp:36 ]: [Client 5-4816] Checking out session: appRoot=/opt/healtheintent/applications/client_solution_configuration_service
[ 2017-07-09 08:55:02.1275 6743/7fb477fff700 age/Cor/Req/CheckoutSession.cpp:50 ]: [Client 5-4816] Request refcount increased; it is now 3
[ 2017-07-09 08:55:02.1275 6743/7fb477fff700 App/Poo/Miscellaneous.cpp:42 ]: asyncGet(appGroupName=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.1275 6743/7fb477fff700 App/Poo/Miscellaneous.cpp:48 ]: Found existing Group
[ 2017-07-09 08:55:02.1276 6743/7fb477fff700 App/Gro/SessionManagement.cpp:329 ]: Session checked out from process (pid=7603, group=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.1276 6743/7fb477fff700 App/Poo/Miscellaneous.cpp:53 ]: asyncGet() finished
[ 2017-07-09 08:55:02.1276 6743/7fb477fff700 age/Cor/Req/CheckoutSession.cpp:114 ]: [Client 5-4816] Session checked out: pid=7603, gupid=17d5ebd-NxAAcIkqYu
[ 2017-07-09 08:55:02.1276 6743/7fb477fff700 App/Socket.h:167 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking out connection from connection pool (1 -> 0 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.1277 6743/7fb477fff700 age/Cor/Req/CheckoutSession.cpp:182 ]: [Client 5-4816] Session initiated: fd=81
[ 2017-07-09 08:55:02.1277 6743/7fb477fff700 age/Cor/Req/SendRequest.cpp:34 ]: [Client 5-4816] Sending headers to application with session protocol
[ 2017-07-09 08:55:02.1277 6743/7fb477fff700 age/Cor/Req/SendRequest.cpp:124 ]: [Client 5-4816] Header data: "\000\000\001\250REQUEST_URI\000/client-solution-configuration-service/meta/availability\000PATH_INFO\000/meta/availability\000SCRIPT_NAME\000/client-solution-configuration-service\000QUERY_STRING\000\000REQUEST_METHOD\000HEAD\000SERVER_NAME\000localhost\000SERVER_PORT\00080\000SERVER_SOFTWARE\000Apache/2.2.15 (Unix) Phusion_Passenger/5.0.18\000SERVER_PROTOCOL\000HTTP/1.1\000REMOTE_ADDR\00010.190.115.28\000REMOTE_PORT\00050852\000PASSENGER_CONNECT_PASSWORD\000W42m1JWqR42WXZSN\000HTTP_X_FORWARDED_PROTO\000https\000"
[ 2017-07-09 08:55:02.1278 6743/7fb477fff700 age/Cor/Req/SendRequest.cpp:846 ]: [Client 5-4816] No body to send to application
[ 2017-07-09 08:55:02.1279 6743/7fb477fff700 age/Cor/Req/CheckoutSession.cpp:75 ]: [Client 5-4816] Request refcount decreased; it is now 2
[ 2017-07-09 08:55:02.1279 6743/7fb477fff700 Ser/HttpServer.h:679 ]: [Client 5-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1279 6743/7fb477fff700 Ser/Server.h:479 ]: [Client 5-4816] 265 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.1279 6743/7fb477fff700 Ser/FdSourceChannel.h:54 ]: [Client 5-4816] Refcount decreased; it is now 2
App 6794 stderr: [ 2017-07-09 08:55:02.1281 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:139 ]: Accepted new request on socket main socket
[ 2017-07-09 08:55:02.1332 6743/7fb477fff700 Ser/FdSourceChannel.h:54 ]: [Client 5-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.1333 6743/7fb477fff700 age/Cor/Req/ForwardResponse.cpp:43 ]: [Client 5-4816] Event: onAppSourceData
[ 2017-07-09 08:55:02.1333 6743/7fb477fff700 age/Cor/Req/ForwardResponse.cpp:54 ]: [Client 5-4816] Processing 340 bytes of application data: "HTTP/1.1 200 Whatever\r\nX-Frame-Options: SAMEORIGIN\r\nX-XSS-Protection: 1; mode=block\r\nX-Content-Type-Options: nosniff\r\nCache-Control: no-cache, no-store, max-age=0, must-revalidate\r\nPragma: no-cache\r\nExpires: -1\r\nContent-Type: text/html\r\nX-Request-Id: a2caeb24-5fe5-406e-a2b9-7c0e9932d66a\r\nX-Runtime: 0.004394\r\nTransfer-Encoding: chunked\r\n\r\n"
[ 2017-07-09 08:55:02.1334 6743/7fb477fff700 age/Cor/Req/ForwardResponse.cpp:66 ]: [Client 5-4816] Application response headers received
[ 2017-07-09 08:55:02.1334 6743/7fb477fff700 age/Cor/Req/ForwardResponse.cpp:800 ]: [Client 5-4816] Sending response headers using writev()
[ 2017-07-09 08:55:02.1334 6743/7fb477fff700 age/Cor/Req/ForwardResponse.cpp:861 ]: [Client 5-4816] Sending response headers: "HTTP/1.1 200 OK\r\nStatus: 200 OK\r\nX-Request-Id: a2caeb24-5fe5-406e-a2b9-7c0e9932d66a\r\nCache-Control: no-cache, no-store, max-age=0, must-revalidate\r\nTransfer-Encoding: chunked\r\nX-Runtime: 0.004394\r\nX-Frame-Options: SAMEORIGIN\r\nX-XSS-Protection: 1; mode=block\r\nX-Content-Type-Options: nosniff\r\nPragma: no-cache\r\nContent-Type: text/html\r\nExpires: -1\r\nDate: Sun, 09 Jul 2017 13:55:02 GMT\r\nConnection: close\r\nX-Powered-By: Phusion Passenger 5.0.18\r\n\r\n"
[ 2017-07-09 08:55:02.1335 6743/7fb477fff700 App/Socket.h:196 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking in connection into connection pool (0 -> 1 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.1335 6743/7fb477fff700 App/Gro/SessionManagement.cpp:149 ]: Session closed for process (pid=7603, group=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.1336 6743/7fb477fff700 Ser/HttpServer.h:1060 ]: [Client 5-4816] Ending request
[ 2017-07-09 08:55:02.1336 6743/7fb477fff700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb468007120] Deinitialize
App 6794 stderr: [ 2017-07-09 08:55:02.1335 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:404 ]: Keep-aliving connection.
[ 2017-07-09 08:55:02.1336 6743/7fb477fff700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0xb81610] Feeding 0 bytes
App 6794 stderr: [ 2017-07-09 08:55:02.1335 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:404 ]: Keep-aliving connection.
[ 2017-07-09 08:55:02.1336 6743/7fb477fff700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0xb81610] Feeding 0 bytes
[ 2017-07-09 08:55:02.1337 6743/7fb477fff700 Ser/FileBufferedChannel.h:484 ]: [FBC 0xb81610] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.1337 6743/7fb477fff700 Ser/FileBufferedChannel.h:552 ]: [FBC 0xb81610] Reader: reading next
[ 2017-07-09 08:55:02.1337 6743/7fb477fff700 Ser/FileBufferedChannel.h:564 ]: [FBC 0xb81610] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.1338 6743/7fb477fff700 Ser/FileBufferedChannel.h:580 ]: [FBC 0xb81610] Reader: EOF fed. Transitioning to RS_TERMINATED
App 6794 stderr: [ 2017-07-09 08:55:02.1336 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:169 ]: Request done.
[ 2017-07-09 08:55:02.1338 6743/7fb477fff700 Ser/FileBufferedChannel.h:537 ]: [FBC 0xb81610] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.1338 6743/7fb477fff700 Ser/HttpServer.h:219 ]: [Client 5-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1339 6743/7fb477fff700 Ser/HttpServer.h:224 ]: [Client 5-4816] Not keeping alive connection, disconnecting client
[ 2017-07-09 08:55:02.1339 6743/7fb477fff700 Ser/HttpServer.h:224 ]: [Client 5-4816] Not keeping alive connection, disconnecting client
[ 2017-07-09 08:55:02.1339 6743/7fb477fff700 Ser/Server.h:896 ]: [Client 5-4816] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.1339 6743/7fb477fff700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xb81610] Deinitialize
[ 2017-07-09 08:55:02.1340 6743/7fb477fff700 Ser/Server.h:906 ]: [Client 5-4816] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.1340 6743/7fb477fff700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/Server.h:917 ]: [Client 5-4816] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/FdSourceChannel.h:54 ]: [Client 5-4816] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.1341 6792/7f65892e27e0 FileDescriptor.h:83 ]: File descriptor closed: 10
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/HttpServer.h:146 ]: [Client 5-4816] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/HttpServer.h:154 ]: [Client 5-4816] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.1342 6743/7fb477fff700 Ser/HttpServer.h:161 ]: [Client 5-4816] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.1342 6743/7fb477fff700 Ser/Server.h:378 ]: [Client 5-4816] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.1342 6743/7fb477fff700 Ser/Server.h:384 ]: [Client 5-4816] Client object added to freelist (127 -> 128)
[ 2017-07-09 08:55:02.1340 6743/7fb477fff700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/Server.h:917 ]: [Client 5-4816] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/FdSourceChannel.h:54 ]: [Client 5-4816] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.1341 6792/7f65892e27e0 FileDescriptor.h:83 ]: File descriptor closed: 10
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/HttpServer.h:146 ]: [Client 5-4816] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.1341 6743/7fb477fff700 Ser/HttpServer.h:154 ]: [Client 5-4816] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.1342 6743/7fb477fff700 Ser/HttpServer.h:161 ]: [Client 5-4816] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.1342 6743/7fb477fff700 Ser/Server.h:378 ]: [Client 5-4816] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.1342 6743/7fb477fff700 Ser/Server.h:384 ]: [Client 5-4816] Client object added to freelist (127 -> 128)
[ 2017-07-09 08:55:02.2574 6743/7fb476bfd700 Ser/Server.h:241 ]: [ApiServer] New clients can be accepted on a server socket
[ 2017-07-09 08:55:02.2576 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.2576 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.2576 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2042] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.2576 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2042] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.2576 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2042
[ 2017-07-09 08:55:02.2576 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2042
[ 2017-07-09 08:55:02.2577 6743/7fb476bfd700 Ser/Server.h:267 ]: [ApiServer] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.2577 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2042] Event: onClientAccepted
[ 2017-07-09 08:55:02.2577 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2042] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.2577 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.2578 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.2578 6743/7fb476bfd700 Ser/Server.h:452 ]: [Client A2042] Processing 206 bytes of client data
[ 2017-07-09 08:55:02.2578 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2042] Event: onClientDataReceived
[ 2017-07-09 08:55:02.2579 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2042] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.2579 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2042] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.2579 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2042] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:02.2579 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2042] New request received: #1
[ 2017-07-09 08:55:02.2580 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.2580 6743/7fb476bfd700 age/ApiServerUtils.h:252 ]: [Client A2042] Authenticated with UID: 0
[ 2017-07-09 08:55:02.2581 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2042] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.2581 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2042] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:02.2584 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 146 bytes
[ 2017-07-09 08:55:02.2584 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:02.2584 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2584 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2585 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.2586 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.2586 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.2586 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 2582
[ 2017-07-09 08:55:02.2586 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2586 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 2582 bytes
[ 2017-07-09 08:55:02.2586 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.2587 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2582 bytes
[ 2017-07-09 08:55:02.2587 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2587 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.2587 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.2587 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2042] Ending request
[ 2017-07-09 08:55:02.2588 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.2588 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.2588 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.2588 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.2588 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.2588 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2042] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2042] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.2589 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2042] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.2590 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2042] Refcount increased; it is now 4
[ 2017-07-09 08:55:02.2590 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.2590 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.2590 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2042] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.2590 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2042] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.2590 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2042] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.2591 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2042] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.2591 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2042] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.2591 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2042] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.2591 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2042] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.2591 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2042] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.2592 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2042] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.2592 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2042] Client sent EOF
[ 2017-07-09 08:55:02.2592 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2042] Event: onClientDataReceived
[ 2017-07-09 08:55:02.2592 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2042] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.2592 6743/7fb476bfd700 Ser/Server.h:896 ]: [Client A2042] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.2593 6743/7fb476bfd700 Ser/HttpServer.h:725 ]: [Client A2042] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.2593 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.2593 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2042] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.2593 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.2593 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.2594 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2042] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.2594 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2042] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.2594 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2042] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.2594 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2042] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.2594 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2042] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.2595 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2042] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.2595 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2042] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.2595 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2042] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.2596 6743/7fb476bfd700 Ser/Server.h:378 ]: [Client A2042] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.2596 6743/7fb476bfd700 Ser/Server.h:387 ]: [Client A2042] Client object destroyed; not added to freelist because it's full (0)
[ 2017-07-09 08:55:02.4833 6743/7fb476bfd700 Ser/Server.h:241 ]: [ApiServer] New clients can be accepted on a server socket
[ 2017-07-09 08:55:02.4834 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.4834 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2043] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.4835 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2043
[ 2017-07-09 08:55:02.4835 6743/7fb476bfd700 Ser/Server.h:267 ]: [ApiServer] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.4835 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2043] Event: onClientAccepted
[ 2017-07-09 08:55:02.4836 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2043] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.4836 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.4836 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.4836 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.4836 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.4836 6743/7fb476bfd700 Ser/Server.h:452 ]: [Client A2043] Processing 206 bytes of client data
[ 2017-07-09 08:55:02.4837 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2043] Event: onClientDataReceived
[ 2017-07-09 08:55:02.4837 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2043] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.4837 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2043] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:02.4838 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2043] New request received: #1
[ 2017-07-09 08:55:02.4838 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.4838 6743/7fb476bfd700 age/ApiServerUtils.h:252 ]: [Client A2043] Authenticated with UID: 0
[ 2017-07-09 08:55:02.4839 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2043] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.4839 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2043] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.4839 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2043] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:02.4843 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 146 bytes
[ 2017-07-09 08:55:02.4844 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:02.4844 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4844 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.4845 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.4846 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 2582
[ 2017-07-09 08:55:02.4846 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4846 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4846 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 2582 bytes
[ 2017-07-09 08:55:02.4846 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
ompleted: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.4846 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2582 bytes
[ 2017-07-09 08:55:02.4847 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4847 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.4847 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.4847 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2043] Ending request
[ 2017-07-09 08:55:02.4847 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.4848 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.4848 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.4848 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.4848 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.4848 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.4848 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2043] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2043] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2043] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2043] Refcount increased; it is now 4
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.4849 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2043] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2043] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2043] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2043] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2043] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2043] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2043] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.4850 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2043] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.4856 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2043] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.4857 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2043] Client sent EOF
[ 2017-07-09 08:55:02.4857 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2043] Event: onClientDataReceived
[ 2017-07-09 08:55:02.4857 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2043] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.4857 6743/7fb476bfd700 Ser/Server.h:896 ]: [Client A2043] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.4858 6743/7fb476bfd700 Ser/HttpServer.h:725 ]: [Client A2043] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.4858 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.4858 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2043] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.4858 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.4859 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2043] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.4859 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2043] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.4859 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2043] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.4859 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2043] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.4860 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2043] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.4860 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2043] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.4860 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2043] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.4860 6743/7fb476bfd700 Ser/Server.h:378 ]: [Client A2043] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.4860 6743/7fb476bfd700 Ser/Server.h:387 ]: [Client A2043] Client object destroyed; not added to freelist because it's full (0)
[ 2017-07-09 08:55:02.6907 6743/7fb476bfd700 Ser/Server.h:241 ]: [ApiServer] New clients can be accepted on a server socket
[ 2017-07-09 08:55:02.6908 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.6908 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.6909 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2044] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.6909 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2044
[ 2017-07-09 08:55:02.6910 6743/7fb476bfd700 Ser/Server.h:267 ]: [ApiServer] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.6910 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2044] Event: onClientAccepted
[ 2017-07-09 08:55:02.6910 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2044] Event: onClientAccepted
[ 2017-07-09 08:55:02.6910 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2044] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.6910 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.6911 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.6911 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.6911 6743/7fb476bfd700 Ser/Server.h:452 ]: [Client A2044] Processing 206 bytes of client data
[ 2017-07-09 08:55:02.6912 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2044] Event: onClientDataReceived
[ 2017-07-09 08:55:02.6912 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2044] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.6912 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2044] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:02.6913 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2044] New request received: #1
[ 2017-07-09 08:55:02.6913 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.6913 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.6913 6743/7fb476bfd700 age/ApiServerUtils.h:252 ]: [Client A2044] Authenticated with UID: 0
[ 2017-07-09 08:55:02.6914 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2044] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.6914 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2044] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:02.6917 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 146 bytes
[ 2017-07-09 08:55:02.6917 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:02.6917 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.6917 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.6917 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.6918 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:02.6918 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.6919 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.6919 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.6919 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.6919 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 2582
[ 2017-07-09 08:55:02.6919 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.6921 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 2582 bytes
[ 2017-07-09 08:55:02.6921 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.6921 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2582 bytes
[ 2017-07-09 08:55:02.6922 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.6922 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.6922 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.6922 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2044] Ending request
[ 2017-07-09 08:55:02.6922 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.6923 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.6923 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.6923 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.6923 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.6924 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.6924 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2044] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.6924 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2044] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.6924 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2044] Refcount increased; it is now 4
[ 2017-07-09 08:55:02.6925 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.6925 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.6925 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2044] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.6925 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2044] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.6925 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2044] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.6926 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2044] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.6926 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2044] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.6926 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2044] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.6926 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2044] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.6927 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2044] Client sent EOF
[ 2017-07-09 08:55:02.6927 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2044] Event: onClientDataReceived
[ 2017-07-09 08:55:02.6927 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2044] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.6927 6743/7fb476bfd700 Ser/Server.h:896 ]: [Client A2044] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.6928 6743/7fb476bfd700 Ser/HttpServer.h:725 ]: [Client A2044] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.6928 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.6928 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2044] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.6928 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.6929 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2044] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.6929 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2044] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.6929 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2044] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.6929 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2044] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.6929 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2044] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.6930 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2044] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.6930 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2044] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.6930 6743/7fb476bfd700 Ser/Server.h:378 ]: [Client A2044] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.6930 6743/7fb476bfd700 Ser/Server.h:387 ]: [Client A2044] Client object destroyed; not added to freelist because it's full (0)
[ 2017-07-09 08:55:02.9215 6743/7fb476bfd700 Ser/Server.h:241 ]: [ApiServer] New clients can be accepted on a server socket
[ 2017-07-09 08:55:02.9217 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:02.9217 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2045] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.9218 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2045
[ 2017-07-09 08:55:02.9218 6743/7fb476bfd700 Ser/Server.h:267 ]: [ApiServer] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:02.9219 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2045] Event: onClientAccepted
[ 2017-07-09 08:55:02.9219 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2045] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.9219 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.9219 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.9220 6743/7fb476bfd700 Ser/Server.h:452 ]: [Client A2045] Processing 206 bytes of client data
[ 2017-07-09 08:55:02.9220 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2045] Event: onClientDataReceived
[ 2017-07-09 08:55:02.9220 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2045] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.9220 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2045] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:02.9221 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2045] New request received: #1
[ 2017-07-09 08:55:02.9221 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2045] New request received: #1
[ 2017-07-09 08:55:02.9221 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.9221 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:02.9222 6743/7fb476bfd700 age/ApiServerUtils.h:252 ]: [Client A2045] Authenticated with UID: 0
[ 2017-07-09 08:55:02.9222 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2045] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.9222 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2045] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:02.9223 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2045] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:02.9223 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2045] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:02.9225 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 146 bytes
[ 2017-07-09 08:55:02.9226 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:02.9226 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9226 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9226 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.9226 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:02.9226 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.9227 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:02.9227 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9227 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.9228 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.9228 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.9228 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2582 bytes
[ 2017-07-09 08:55:02.9228 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 2582
[ 2017-07-09 08:55:02.9228 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9228 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 2582 bytes
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2582 bytes
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2582 bytes
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:02.9229 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.9230 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2045] Ending request
[ 2017-07-09 08:55:02.9230 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.9230 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:02.9230 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.9230 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:02.9230 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2045] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2045] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2045] Keeping alive connection, handling next request
[ 2017-07-09 08:55:02.9231 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2045] Refcount increased; it is now 4
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2045] Request refcount decreased; it is now 0
r.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2045] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2045] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2045] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.9233 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2045] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.9233 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2045] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.9233 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2045] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2045] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.9232 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2045] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:02.9233 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2045] Refcount decreased; it is now 3
[ 2017-07-09 08:55:02.9233 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2045] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.9233 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2045] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.9239 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2045] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.9240 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2045] Client sent EOF
[ 2017-07-09 08:55:02.9240 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2045] Event: onClientDataReceived
[ 2017-07-09 08:55:02.9240 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2045] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.9240 6743/7fb476bfd700 Ser/Server.h:896 ]: [Client A2045] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.9240 6743/7fb476bfd700 Ser/HttpServer.h:725 ]: [Client A2045] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9241 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:02.9241 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2045] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.9241 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.9241 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2045] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.9242 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2045] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.9242 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2045] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.9242 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2045] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.9242 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2045] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9243 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2045] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.9243 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2045] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.9243 6743/7fb476bfd700 Ser/Server.h:378 ]: [Client A2045] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.9244 6743/7fb476bfd700 Ser/Server.h:387 ]: [Client A2045] Client object destroyed; not added to freelist because it's full (0)
[ 2017-07-09 08:55:02.9568 6781/7f65892e27e0 apa/Hooks.cpp:275 ]: File descriptor opened: 10
[ 2017-07-09 08:55:02.9570 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: 66
[ 2017-07-09 08:55:02.9571 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:135 ]: Accepted client file descriptor: 66
[ 2017-07-09 08:55:02.9571 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:193 ]: File descriptor opened: -1
[ 2017-07-09 08:55:02.9572 6743/7fb4761fc700 Ser/AcceptLoadBalancer.h:163 ]: Feeding client to server thread 5: file descriptor 66
[ 2017-07-09 08:55:02.9574 6743/7fb4775fe700 age/Cor/Req/TurboCaching.h:242 ]: Clearing turbocache
[ 2017-07-09 08:55:02.9575 6743/7fb4775fe700 Ser/Server.h:352 ]: [ServerThr.6] Checking out client object from freelist (128 -> 127)
[ 2017-07-09 08:55:02.9576 6743/7fb4775fe700 Ser/Server.h:575 ]: [Client 6-4816] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.9576 6743/7fb4775fe700 Ser/Server.h:575 ]: [Client 6-4816] Client associated with file descriptor: 66
[ 2017-07-09 08:55:02.9576 6743/7fb4775fe700 Ser/Server.h:726 ]: File descriptor purpose: 66: Server ServerThr.6, client 6-4816
[ 2017-07-09 08:55:02.9577 6743/7fb4775fe700 Ser/Server.h:730 ]: [ServerThr.6] 1 new client(s) accepted; there are now 1 active client(s)
ed; there are now 1 active client(s)
[ 2017-07-09 08:55:02.9577 6743/7fb4775fe700 Ser/HttpServer.h:668 ]: [Client 6-4816] Event: onClientAccepted
[ 2017-07-09 08:55:02.9577 6743/7fb4775fe700 Ser/HttpServer.h:668 ]: [Client 6-4816] Event: onClientAccepted
[ 2017-07-09 08:55:02.9577 6743/7fb4775fe700 Ser/HttpServer.h:235 ]: [Client 6-4816] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.9577 6743/7fb4775fe700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xba5280] Deinitialize
[ 2017-07-09 08:55:02.9578 6743/7fb4775fe700 Ser/HttpServer.h:119 ]: [ServerThr.6] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:02.9578 6743/7fb4775fe700 Ser/Server.h:452 ]: [Client 6-4816] Processing 464 bytes of client data
[ 2017-07-09 08:55:02.9579 6743/7fb4775fe700 Ser/HttpServer.h:676 ]: [Client 6-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.9579 6743/7fb4775fe700 Ser/HttpServer.h:679 ]: [Client 6-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.9579 6743/7fb4775fe700 Ser/HttpServer.h:256 ]: [Client 6-4816] Parsing 464 bytes of HTTP header: "HEAD /client-solution-configuration-service/meta/availability HTTP/1.1\r\nX-Forwarded-Proto: https\r\nConnection: close\r\n!~: kbdnBq92lKPPhbOsZhFlmKsr\r\n!~DOCUMENT_ROOT: /opt/healtheintent/applications/client_solution_configuration_service/public\r\n!~SCRIPT_NAME: /client-solution-configuration-service\r\n!~REMOTE_ADDR: 10.190.133.44\r\n!~REMOTE_PORT: 34592\r\n!~PASSENGER_APP_GROUP_NAME: /opt/healtheintent/applications/client_solution_configuration_service\r\n!~PASSENGER_APP_"
[ 2017-07-09 08:55:02.9580 6743/7fb4775fe700 Ser/HttpServer.h:679 ]: [Client 6-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9580 6743/7fb4775fe700 Ser/Server.h:479 ]: [Client 6-4816] 464 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.9580 6743/7fb4775fe700 Ser/Server.h:528 ]: [Client 6-4816] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.9580 6743/7fb4775fe700 Ser/Server.h:528 ]: [Client 6-4816] Refcount decreased; it is now 2
[ 2017-07-09 08:55:02.9581 6743/7fb4775fe700 Ser/FdSourceChannel.h:54 ]: [Client 6-4816] Refcount increased; it is now 3
[ 2017-07-09 08:55:02.9581 6743/7fb4775fe700 Ser/Server.h:452 ]: [Client 6-4816] Processing 265 bytes of client data
[ 2017-07-09 08:55:02.9582 6743/7fb4775fe700 Ser/HttpServer.h:676 ]: [Client 6-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.9581 6743/7fb4775fe700 Ser/Server.h:452 ]: [Client 6-4816] Processing 265 bytes of client data
[ 2017-07-09 08:55:02.9582 6743/7fb4775fe700 Ser/HttpServer.h:676 ]: [Client 6-4816] Event: onClientDataReceived
[ 2017-07-09 08:55:02.9582 6743/7fb4775fe700 Ser/HttpServer.h:679 ]: [Client 6-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.9582 6743/7fb4775fe700 Ser/HttpServer.h:256 ]: [Client 6-4816] Parsing 265 bytes of HTTP header: "ROOT: /opt/healtheintent/applications/client_solution_configuration_service\r\n!~PASSENGER_APP_TYPE: rack\r\n!~PASSENGER_RUBY: /usr/local/ruby/2.2.3/bin/ruby\r\n!~PASSENGER_MIN_PROCESSES: 18\r\n!~PASSENGER_MAX_REQUESTS: 30\r\n!~PASSENGER_SPAWN_METHOD: smart\r\n!~FLAGS: CDB\r\n\r\n"
[ 2017-07-09 08:55:02.9582 6743/7fb4775fe700 Ser/HttpServer.h:267 ]: [Client 6-4816] New request received: #4816
[ 2017-07-09 08:55:02.9583 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:59 ]: [Client 6-4816] Initiating request
[ 2017-07-09 08:55:02.9583 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:124 ]: [Client 6-4816] Dechunk flag detected
[ 2017-07-09 08:55:02.9583 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:124 ]: [Client 6-4816] Dechunk flag detected
[ 2017-07-09 08:55:02.9583 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:127 ]: [Client 6-4816] Request body buffering enabled
[ 2017-07-09 08:55:02.9584 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:133 ]: [Client 6-4816] Stripping 100 Continue header
[ 2017-07-09 08:55:02.9583 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:127 ]: [Client 6-4816] Request body buffering enabled
[ 2017-07-09 08:55:02.9584 6743/7fb4775fe700 age/Cor/Req/InitRequest.cpp:133 ]: [Client 6-4816] Stripping 100 Continue header
[ 2017-07-09 08:55:02.9584 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:36 ]: [Client 6-4816] Checking out session: appRoot=/opt/healtheintent/applications/client_solution_configuration_service
[ 2017-07-09 08:55:02.9584 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:50 ]: [Client 6-4816] Request refcount increased; it is now 3
[ 2017-07-09 08:55:02.9584 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:50 ]: [Client 6-4816] Request refcount increased; it is now 3
[ 2017-07-09 08:55:02.9584 6743/7fb4775fe700 App/Poo/Miscellaneous.cpp:42 ]: asyncGet(appGroupName=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.9585 6743/7fb4775fe700 App/Poo/Miscellaneous.cpp:48 ]: Found existing Group
[ 2017-07-09 08:55:02.9585 6743/7fb4775fe700 App/Gro/SessionManagement.cpp:329 ]: Session checked out from process (pid=7603, group=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.9586 6743/7fb4775fe700 App/Poo/Miscellaneous.cpp:53 ]: asyncGet() finished
[ 2017-07-09 08:55:02.9586 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:114 ]: [Client 6-4816] Session checked out: pid=7603, gupid=17d5ebd-NxAAcIkqYu
[ 2017-07-09 08:55:02.9586 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:114 ]: [Client 6-4816] Session checked out: pid=7603, gupid=17d5ebd-NxAAcIkqYu
[ 2017-07-09 08:55:02.9586 6743/7fb4775fe700 App/Socket.h:167 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking out connection from connection pool (1 -> 0 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.9586 6743/7fb4775fe700 App/Socket.h:167 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking out connection from connection pool (1 -> 0 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.9587 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:182 ]: [Client 6-4816] Session initiated: fd=81
[ 2017-07-09 08:55:02.9587 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:182 ]: [Client 6-4816] Session initiated: fd=81
[ 2017-07-09 08:55:02.9587 6743/7fb4775fe700 age/Cor/Req/SendRequest.cpp:34 ]: [Client 6-4816] Sending headers to application with session protocol
[ 2017-07-09 08:55:02.9587 6743/7fb4775fe700 age/Cor/Req/SendRequest.cpp:34 ]: [Client 6-4816] Sending headers to application with session protocol
[ 2017-07-09 08:55:02.9588 6743/7fb4775fe700 age/Cor/Req/SendRequest.cpp:124 ]: [Client 6-4816] Header data: "\000\000\001\250REQUEST_URI\000/client-solution-configuration-service/meta/availability\000PATH_INFO\000/meta/availability\000SCRIPT_NAME\000/client-solution-configuration-service\000QUERY_STRING\000\000REQUEST_METHOD\000HEAD\000SERVER_NAME\000localhost\000SERVER_PORT\00080\000SERVER_SOFTWARE\000Apache/2.2.15 (Unix) Phusion_Passenger/5.0.18\000SERVER_PROTOCOL\000HTTP/1.1\000REMOTE_ADDR\00010.190.133.44\000REMOTE_PORT\00034592\000PASSENGER_CONNECT_PASSWORD\000W42m1JWqR42WXZSN\000HTTP_X_FORWARDED_PROTO\000https\000"
[ 2017-07-09 08:55:02.9589 6743/7fb4775fe700 age/Cor/Req/SendRequest.cpp:846 ]: [Client 6-4816] No body to send to application
[ 2017-07-09 08:55:02.9589 6743/7fb4775fe700 age/Cor/Req/CheckoutSession.cpp:75 ]: [Client 6-4816] Request refcount decreased; it is now 2
[ 2017-07-09 08:55:02.9589 6743/7fb4775fe700 Ser/HttpServer.h:679 ]: [Client 6-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9589 6743/7fb4775fe700 Ser/Server.h:479 ]: [Client 6-4816] 265 bytes of client data consumed in this callback
[ 2017-07-09 08:55:02.9589 6743/7fb4775fe700 Ser/FdSourceChannel.h:54 ]: [Client 6-4816] Refcount decreased; it is now 2
App 6794 stderr: [ 2017-07-09 08:55:02.9591 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:139 ]: Accepted new request on socket main socket
[ 2017-07-09 08:55:02.9641 6743/7fb4775fe700 Ser/FdSourceChannel.h:54 ]: [Client 6-4816] Request refcount increased; it is now 2
[ 2017-07-09 08:55:02.9642 6743/7fb4775fe700 age/Cor/Req/ForwardResponse.cpp:43 ]: [Client 6-4816] Event: onAppSourceData
[ 2017-07-09 08:55:02.9643 6743/7fb4775fe700 age/Cor/Req/ForwardResponse.cpp:54 ]: [Client 6-4816] Processing 340 bytes of application data: "HTTP/1.1 200 Whatever\r\nX-Frame-Options: SAMEORIGIN\r\nX-XSS-Protection: 1; mode=block\r\nX-Content-Type-Options: nosniff\r\nCache-Control: no-cache, no-store, max-age=0, must-revalidate\r\nPragma: no-cache\r\nExpires: -1\r\nContent-Type: text/html\r\nX-Request-Id: 07e0be07-112d-4e5e-af7f-3ce624c6463f\r\nX-Runtime: 0.004471\r\nTransfer-Encoding: chunked\r\n\r\n"
[ 2017-07-09 08:55:02.9644 6743/7fb4775fe700 age/Cor/Req/ForwardResponse.cpp:66 ]: [Client 6-4816] Application response headers received
[ 2017-07-09 08:55:02.9644 6743/7fb4775fe700 age/Cor/Req/ForwardResponse.cpp:800 ]: [Client 6-4816] Sending response headers using writev()
App 6794 stderr: [ 2017-07-09 08:55:02.9643 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:404 ]: Keep-aliving connection.
[ 2017-07-09 08:55:02.9645 6743/7fb4775fe700 age/Cor/Req/ForwardResponse.cpp:861 ]: [Client 6-4816] Sending response headers: "HTTP/1.1 200 OK\r\nStatus: 200 OK\r\nX-Request-Id: 07e0be07-112d-4e5e-af7f-3ce624c6463f\r\nCache-Control: no-cache, no-store, max-age=0, must-revalidate\r\nTransfer-Encoding: chunked\r\nX-Runtime: 0.004471\r\nX-Frame-Options: SAMEORIGIN\r\nX-XSS-Protection: 1; mode=block\r\nX-Content-Type-Options: nosniff\r\nPragma: no-cache\r\nContent-Type: text/html\r\nExpires: -1\r\nDate: Sun, 09 Jul 2017 13:55:02 GMT\r\nConnection: close\r\nX-Powered-By: Phusion Passenger 5.0.18\r\n\r\n"
[ 2017-07-09 08:55:02.9645 6743/7fb4775fe700 App/Socket.h:196 ]: Socket unix:/tmp/passenger.Z5XpabV/apps.s/ruby.L7uHlSo5VlaHjyjRPPqMa0MtAv3snwTcNf19eWbQXsfu36byEtNJtxftwvdP1aD: checking in connection into connection pool (0 -> 1 items). Current total number of connections: 1
[ 2017-07-09 08:55:02.9646 6743/7fb4775fe700 App/Gro/SessionManagement.cpp:149 ]: Session closed for process (pid=7603, group=/opt/healtheintent/applications/client_solution_configuration_service)
[ 2017-07-09 08:55:02.9646 6743/7fb4775fe700 Ser/HttpServer.h:1060 ]: [Client 6-4816] Ending request
[ 2017-07-09 08:55:02.9646 6743/7fb4775fe700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb46c000e70] Deinitialize
[ 2017-07-09 08:55:02.9646 6743/7fb4775fe700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb46c000e70] Deinitialize
App 6794 stderr: [ 2017-07-09 08:55:02.9646 7603/0x007f2016eceea0(Worker 1) request_handler/thread_handler.rb:169 ]: Request done.
[ 2017-07-09 08:55:02.9647 6743/7fb4775fe700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0xba5280] Feeding 0 bytes
[ 2017-07-09 08:55:02.9647 6743/7fb4775fe700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0xba5280] Feeding 0 bytes
[ 2017-07-09 08:55:02.9647 6743/7fb4775fe700 Ser/FileBufferedChannel.h:484 ]: [FBC 0xba5280] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:02.9647 6743/7fb4775fe700 Ser/FileBufferedChannel.h:552 ]: [FBC 0xba5280] Reader: reading next
[ 2017-07-09 08:55:02.9647 6743/7fb4775fe700 Ser/FileBufferedChannel.h:552 ]: [FBC 0xba5280] Reader: reading next
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/FileBufferedChannel.h:564 ]: [FBC 0xba5280] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/FileBufferedChannel.h:564 ]: [FBC 0xba5280] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/FileBufferedChannel.h:580 ]: [FBC 0xba5280] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/FileBufferedChannel.h:537 ]: [FBC 0xba5280] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/FileBufferedChannel.h:537 ]: [FBC 0xba5280] Calling dataFlushedCallback
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/HttpServer.h:219 ]: [Client 6-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9648 6743/7fb4775fe700 Ser/HttpServer.h:219 ]: [Client 6-4816] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9649 6743/7fb4775fe700 Ser/HttpServer.h:224 ]: [Client 6-4816] Not keeping alive connection, disconnecting client
[ 2017-07-09 08:55:02.9649 6743/7fb4775fe700 Ser/Server.h:896 ]: [Client 6-4816] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:02.9649 6743/7fb4775fe700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0xba5280] Deinitialize
[ 2017-07-09 08:55:02.9649 6743/7fb4775fe700 Ser/Server.h:906 ]: [Client 6-4816] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.9649 6743/7fb4775fe700 Ser/Server.h:906 ]: [Client 6-4816] Closing client file descriptor: 66
[ 2017-07-09 08:55:02.9650 6743/7fb4775fe700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:02.9650 6743/7fb4775fe700 Ser/Server.h:917 ]: [Client 6-4816] Refcount decreased; it is now 1
[ 2017-07-09 08:55:02.9650 6743/7fb4775fe700 Ser/FdSourceChannel.h:54 ]: [Client 6-4816] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:02.9651 6743/7fb4775fe700 Ser/HttpServer.h:146 ]: [Client 6-4816] Request object reached a reference count of 0
[ 2017-07-09 08:55:02.9651 6781/7f65892e27e0 FileDescriptor.h:83 ]: File descriptor closed: 10
[ 2017-07-09 08:55:02.9651 6743/7fb4775fe700 Ser/HttpServer.h:154 ]: [Client 6-4816] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:02.9651 6743/7fb4775fe700 Ser/HttpServer.h:161 ]: [Client 6-4816] Refcount decreased; it is now 0
[ 2017-07-09 08:55:02.9651 6743/7fb4775fe700 Ser/Server.h:378 ]: [Client 6-4816] Client object reached a reference count of 0
[ 2017-07-09 08:55:02.9651 6743/7fb4775fe700 Ser/Server.h:384 ]: [Client 6-4816] Client object added to freelist (127 -> 128)
[ 2017-07-09 08:55:03.6009 6743/7fb476bfd700 Ser/Server.h:241 ]: [ApiServer] New clients can be accepted on a server socket
[ 2017-07-09 08:55:03.6010 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:03.6010 6743/7fb476bfd700 Ser/Server.h:363 ]: [ApiServer] Creating new client object
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2046] Client associated with file descriptor: 66
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/Server.h:575 ]: [Client A2046] Client associated with file descriptor: 66
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2046
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/Server.h:261 ]: File descriptor purpose: 66: Server ApiServer, client A2046
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/Server.h:267 ]: [ApiServer] 1 new client(s) accepted; there are now 1 active client(s)
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2046] Event: onClientAccepted
[ 2017-07-09 08:55:03.6011 6743/7fb476bfd700 Ser/HttpServer.h:668 ]: [Client A2046] Event: onClientAccepted
[ 2017-07-09 08:55:03.6012 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2046] Refcount increased; it is now 3
[ 2017-07-09 08:55:03.6012 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:03.6012 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:03.6012 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (2 -> 1)
[ 2017-07-09 08:55:03.6013 6743/7fb476bfd700 Ser/Server.h:452 ]: [Client A2046] Processing 206 bytes of client data
[ 2017-07-09 08:55:03.6013 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2046] Event: onClientDataReceived
[ 2017-07-09 08:55:03.6013 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2046] Event: onClientDataReceived
[ 2017-07-09 08:55:03.6014 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2046] Request refcount increased; it is now 2
[ 2017-07-09 08:55:03.6014 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2046] Request refcount increased; it is now 2
[ 2017-07-09 08:55:03.6014 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2046] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:03.6014 6743/7fb476bfd700 Ser/HttpServer.h:256 ]: [Client A2046] Parsing 206 bytes of HTTP header: "GET /pool.txt?colorize=false&verbose= HTTP/1.1\r\nAccept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3\r\nAccept: */*\r\nUser-Agent: Ruby\r\nAuthorization: Basic cm9fYWRtaW46UHB1S21SQTBrTVBZUmxrZFFMZFdoZWpX\r\n\r\n"
[ 2017-07-09 08:55:03.6014 6743/7fb476bfd700 Ser/HttpServer.h:267 ]: [Client A2046] New request received: #1
[ 2017-07-09 08:55:03.6015 6743/7fb476bfd700 age/Cor/ApiServer.h:517 ]: API request: GET /pool.txt?colorize=false&verbose=
[ 2017-07-09 08:55:03.6015 6743/7fb476bfd700 age/ApiServerUtils.h:252 ]: [Client A2046] Authenticated with UID: 0
[ 2017-07-09 08:55:03.6015 6743/7fb476bfd700 age/ApiServerUtils.h:277 ]: [Client A2046] HTTP basic authentication supplied: ro_admin
[ 2017-07-09 08:55:03.6016 6743/7fb476bfd700 age/ApiServerUtils.h:291 ]: [Client A2046] Authenticated with administrator account: ro_admin
[ 2017-07-09 08:55:03.6018 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 146 bytes
[ 2017-07-09 08:55:03.6018 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 146
[ 2017-07-09 08:55:03.6018 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:03.6018 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 146 bytes
[ 2017-07-09 08:55:03.6019 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:03.6019 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 146 bytes
[ 2017-07-09 08:55:03.6019 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:03.6020 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:03.6020 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:03.6020 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 2586 bytes
[ 2017-07-09 08:55:03.6020 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 2586
[ 2017-07-09 08:55:03.6020 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:03.6020 6743/7fb476bfd700 Ser/FileBufferedChannel.h:584 ]: [FBC 0x7fb464000a00] Reader: found buffer, 2586 bytes
[ 2017-07-09 08:55:03.6021 6743/7fb476bfd700 Ser/FileBufferedChannel.h:491 ]: [FBC 0x7fb464000a00] popBuffer() completed: nbuffers = 0, bytesBuffered = 0
[ 2017-07-09 08:55:03.6021 6743/7fb476bfd700 Ser/FileBufferedChannel.h:592 ]: [FBC 0x7fb464000a00] Reader: feeding buffer, 2586 bytes
[ 2017-07-09 08:55:03.6021 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:03.6021 6743/7fb476bfd700 Ser/FileBufferedChannel.h:559 ]: [FBC 0x7fb464000a00] Reader: no more buffers. Transitioning to RS_INACTIVE
[ 2017-07-09 08:55:03.6021 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:03.6021 6743/7fb476bfd700 Ser/HttpServer.h:1060 ]: [Client A2046] Ending request
[ 2017-07-09 08:55:03.6022 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1405 ]: [FBC 0x7fb464000a00] Feeding 0 bytes
[ 2017-07-09 08:55:03.6022 6743/7fb476bfd700 Ser/FileBufferedChannel.h:484 ]: [FBC 0x7fb464000a00] pushBuffer() completed: nbuffers = 1, bytesBuffered = 0
[ 2017-07-09 08:55:03.6022 6743/7fb476bfd700 Ser/FileBufferedChannel.h:552 ]: [FBC 0x7fb464000a00] Reader: reading next
[ 2017-07-09 08:55:03.6022 6743/7fb476bfd700 Ser/FileBufferedChannel.h:564 ]: [FBC 0x7fb464000a00] Reader: EOF encountered. Feeding EOF
[ 2017-07-09 08:55:03.6022 6743/7fb476bfd700 Ser/FileBufferedChannel.h:580 ]: [FBC 0x7fb464000a00] Reader: EOF fed. Transitioning to RS_TERMINATED
[ 2017-07-09 08:55:03.6023 6743/7fb476bfd700 Ser/FileBufferedChannel.h:537 ]: [FBC 0x7fb464000a00] Calling dataFlushedCallback
[ 2017-07-09 08:55:03.6023 6743/7fb476bfd700 Ser/HttpServer.h:219 ]: [Client A2046] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:03.6023 6743/7fb476bfd700 Ser/HttpServer.h:221 ]: [Client A2046] Keeping alive connection, handling next request
[ 2017-07-09 08:55:03.6023 6743/7fb476bfd700 Ser/HttpServer.h:235 ]: [Client A2046] Refcount increased; it is now 4
[ 2017-07-09 08:55:03.6023 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:03.6024 6743/7fb476bfd700 Ser/HttpServer.h:119 ]: [ApiServer] Checking out request object from freelist (1 -> 0)
[ 2017-07-09 08:55:03.6024 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2046] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:03.6024 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2046] Request object reached a reference count of 0
[ 2017-07-09 08:55:03.6024 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2046] Request object added to freelist (0 -> 1)
[ 2017-07-09 08:55:03.6025 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2046] Refcount decreased; it is now 3
[ 2017-07-09 08:55:03.6025 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2046] 206 bytes of client data consumed in this callback
[ 2017-07-09 08:55:03.6025 6743/7fb476bfd700 Ser/Server.h:528 ]: [Client A2046] Refcount decreased; it is now 2
[ 2017-07-09 08:55:03.6025 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2046] Refcount increased; it is now 3
[ 2017-07-09 08:55:03.6026 6743/7fb476bfd700 Ser/Server.h:454 ]: [Client A2046] Client sent EOF
[ 2017-07-09 08:55:03.6026 6743/7fb476bfd700 Ser/HttpServer.h:676 ]: [Client A2046] Event: onClientDataReceived
[ 2017-07-09 08:55:03.6026 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2046] Request refcount increased; it is now 2
[ 2017-07-09 08:55:03.6026 6743/7fb476bfd700 Ser/Server.h:896 ]: [Client A2046] Disconnecting; there are now 0 active clients
[ 2017-07-09 08:55:03.6026 6743/7fb476bfd700 Ser/HttpServer.h:725 ]: [Client A2046] Request refcount decreased; it is now 1
[ 2017-07-09 08:55:03.6026 6743/7fb476bfd700 Ser/FileBufferedChannel.h:1449 ]: [FBC 0x7fb464000a00] Deinitialize
[ 2017-07-09 08:55:03.6027 6743/7fb476bfd700 Ser/Server.h:906 ]: [Client A2046] Closing client file descriptor: 66
[ 2017-07-09 08:55:03.6027 6743/7fb476bfd700 Ser/Server.h:909 ]: File descriptor closed: 66
[ 2017-07-09 08:55:03.6027 6743/7fb476bfd700 Ser/Server.h:917 ]: [Client A2046] Refcount decreased; it is now 2
[ 2017-07-09 08:55:03.6027 6743/7fb476bfd700 Ser/HttpServer.h:679 ]: [Client A2046] Request refcount decreased; it is now 0
[ 2017-07-09 08:55:03.6027 6743/7fb476bfd700 Ser/HttpServer.h:146 ]: [Client A2046] Request object reached a reference count of 0
[ 2017-07-09 08:55:03.6028 6743/7fb476bfd700 Ser/HttpServer.h:154 ]: [Client A2046] Request object added to freelist (1 -> 2)
[ 2017-07-09 08:55:03.6028 6743/7fb476bfd700 Ser/HttpServer.h:161 ]: [Client A2046] Refcount decreased; it is now 1
[ 2017-07-09 08:55:03.6028 6743/7fb476bfd700 Ser/Server.h:479 ]: [Client A2046] 0 bytes of client data consumed in this callback
[ 2017-07-09 08:55:03.6028 6743/7fb476bfd700 Ser/FdSourceChannel.h:54 ]: [Client A2046] Refcount decreased; it is now 0
[ 2017-07-09 08:55:03.6028 6743/7fb476bfd700 Ser/Server.h:378 ]: [Client A2046] Client object reached a reference count of 0
[ 2017-07-09 08:55:03.6028 6743/7fb476bfd700 Ser/Server.h:387 ]: [Client A2046] Client object destroyed; not added to freelist because it's full (0)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment