Log for pmcd on bozo-laptop started Mon Mar 11 19:28:18 2013 active agent dom pid in out ver protocol parameters ============ === ===== === === === ======== ========== dummyproc 160 9279 5 6 2 bin pipe cmd=/home/kenj/src/pcp/qa/src/dumb_pmda -d 160 000000660066 pmcd 2 2 dso i:5 lib=/var/lib/pcp/pmdas/pmcd/pmda_pmcd.so entry=pmcd_init [0xb778a3f0] linux 60 2 dso i:4 lib=/var/lib/pcp/pmdas/linux/pmda_linux.so entry=linux_init [0xb73249e0] Host access list empty: access control turned off pmcd: PID = 9269, PDU version = 2 pmcd request port(s): sts fd port family address === ==== ===== ====== ======= ok 1024 44321 inet INADDR_ANY ok 1025 44321 ipv6 INADDR_ANY end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 ->19:28:18 Recv: PROFILE PDU, fd=1027, pdubuf=0xb97a5000 -> Recv: FETCH PDU, fd=1027, pdubuf=0xb97a6000 -> Xmit: RESULT PDU, fd=1027, numpmid=1 -> Recv: RESULT PDU, fd=1027, pdubuf=0xb97a5000 -> Xmit: ERROR PDU, fd=1027, err=0: No error -> End client: fd=1027 -> Xmit: ERROR PDU, fd=5, err=-12391: Not Connected -> New client: from=0.0.0.0, fd=1027, seq=3 -> Xmit: ERROR PDU, fd=1027, err=0: No error -> Recv: CREDS PDU, fd=1027, pdubuf=0xb97a6000 -> Recv: CREDS PDU, fd=1027, pdubuf=0x1 -> Recv: PMNS_NAMES PDU, fd=1027, pdubuf=0xb97a5000 -> Xmit: PMNS_IDS PDU, fd=1027, numpmid=1 -> Recv: DESC_REQ PDU, fd=1027, pdubuf=0xb97a6000 -> Xmit: DESC PDU, fd=1027, pmid=2.0.14 -> Recv: PROFILE PDU, fd=1027, pdubuf=0xb97a5000 -> Recv: FETCH PDU, fd=1027, pdubuf=0xb97a6000 -> Xmit: RESULT PDU, fd=1027, numpmid=1 -> Recv: RESULT PDU, fd=1027, pdubuf=0xb97a5000 -> Xmit: ERROR PDU, fd=1027, err=0: No error ->19:28:24 End client: fd=1027 ->19:28:24 Xmit: ERROR PDU, fd=5, err=-12391: Not Connected ->19:28:24 New client: from=0.0.0.0, fd=1027, seq=4 ->19:28:30 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:28:30 Recv: CREDS PDU, fd=1027, pdubuf=0xb97a6000 ->19:28:30 Recv: CREDS PDU, fd=1027, pdubuf=0x1 ->19:28:30 Recv: PMNS_NAMES PDU, fd=1027, pdubuf=0xb97a5000 ->19:28:30 Xmit: PMNS_IDS PDU, fd=1027, numpmid=1 ->19:28:30 Recv: DESC_REQ PDU, fd=1027, pdubuf=0xb97a6000 ->19:28:30 Xmit: DESC PDU, fd=1027, pmid=2.0.4 end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 ->19:28:30 Recv: PROFILE PDU, fd=1027, pdubuf=0xb97a5000 ->19:28:30 Recv: FETCH PDU, fd=1027, pdubuf=0xb97a6000 ->19:28:30 Xmit: RESULT PDU, fd=1027, numpmid=1 ->19:28:30 Recv: RESULT PDU, fd=1027, pdubuf=0xb97a5000 ->19:28:30 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:28:30 End client: fd=1027 ->19:28:30 Xmit: ERROR PDU, fd=5, err=-12391: Not Connected ->19:28:30 New client: from=0.0.0.0, fd=1027, seq=5 ->19:28:35 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:28:35 Recv: CREDS PDU, fd=1027, pdubuf=0xb97a6000 ->19:28:35 Recv: CREDS PDU, fd=1027, pdubuf=0x1 ->19:28:35 Recv: DESC_REQ PDU, fd=1027, pdubuf=0xb97a5000 ->19:28:35 Xmit: DESC_REQ PDU, fd=5, pmid=160.0.0 before __pmGetPDU: _pmcd_timeout=5 [Mon Mar 11 19:28:40] pmcd(9269) Warning: pduread: timeout (after 5.000 sec) while attempting to read 12 bytes out of 12 in HDR on fd=6 after __pmGetPDU: sts=-12353 ->19:28:40 Wrong PDU type: expecting DESC PDU, fd=6, got err=-12353: Timeout waiting for a response from PMCD after pmcd_trace: sts=-12366 ->19:28:40 Drop PMDA: domain=160, infd=5, outfd=6 [Mon Mar 11 19:28:40] pmcd(9269) Info: CleanupAgent ... Cleanup "dummyproc" agent (dom 160): protocol failure for fd=6, exit(0) end of DoDesc: sts=-12366 back from DoDesc: sts=-12366 connected=1 ->19:28:41 Xmit: ERROR PDU, fd=1027, err=-12366: IPC protocol failure ->19:28:41 End client: fd=1027 ->19:28:43 New client: from=0.0.0.0, fd=1027, seq=6 ->19:28:49 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:28:49 Recv: CREDS PDU, fd=1027, pdubuf=0xb97a6000 ->19:28:49 Recv: CREDS PDU, fd=1027, pdubuf=0x1 ->19:28:49 Recv: DESC_REQ PDU, fd=1027, pdubuf=0xb97a5000 back from DoDesc: sts=-12386 connected=1 ->19:28:49 Xmit: ERROR PDU, fd=1027, err=-12386: No PMCD agent for domain of request ->19:28:49 End client: fd=1027 [Mon Mar 11 19:28:49] pmcd(9269) Info: pmcd RESTARTED at Mon Mar 11 19:28:49 2013 Current PMCD clients ... fd client connection from ipc ver operations denied == ======================================== ======= ================= 1026 bozo-laptop.localdomain 2 Configuration file '/etc/pcp/pmcd/pmcd.conf' unchanged Restarting any deceased agents: "dummyproc" agent ->19:28:49 Recv: TYPE-0? PDU, fd=12, pdubuf=0xb97a6000 ->19:28:49 Xmit: CREDS PDU, fd=6, numcreds=1 ->19:28:49 Add PMDA: domain=160, infd=6, outfd=12 active agent dom pid in out ver protocol parameters ============ === ===== === === === ======== ========== dummyproc 160 9515 6 12 2 bin pipe cmd=/home/kenj/src/pcp/qa/src/dumb_pmda -d 160 000000660066 pmcd 2 2 dso i:5 lib=/var/lib/pcp/pmdas/pmcd/pmda_pmcd.so entry=pmcd_init [0xb778a3f0] linux 60 2 dso i:4 lib=/var/lib/pcp/pmdas/linux/pmda_linux.so entry=linux_init [0xb73249e0] Host access list empty: access control turned off [Mon Mar 11 19:28:49] pmcd(9269) Info: PMNS file "DEFAULT" is unchanged ->19:28:50 New client: from=0.0.0.0, fd=1027, seq=7 ->19:28:55 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:28:55 Recv: CREDS PDU, fd=1027, pdubuf=0xb97a5000 ->19:28:55 Recv: CREDS PDU, fd=1027, pdubuf=0x1 ->19:28:55 Recv: PROFILE PDU, fd=1027, pdubuf=0xb97a6000 ->19:28:55 Recv: FETCH PDU, fd=1027, pdubuf=0xb97a5000 ->19:28:55 Xmit: RESULT PDU, fd=1027, numpmid=1 ->19:28:55 End client: fd=1027 ->19:28:55 Xmit: ERROR PDU, fd=6, err=-12391: Not Connected ->19:28:56 New client: from=0.0.0.0, fd=1027, seq=8 ->19:29:01 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:29:01 Recv: CREDS PDU, fd=1027, pdubuf=0xb97a6000 ->19:29:01 Recv: CREDS PDU, fd=1027, pdubuf=0x1 ->19:29:01 Recv: PMNS_NAMES PDU, fd=1027, pdubuf=0xb97a5000 ->19:29:01 Xmit: PMNS_IDS PDU, fd=1027, numpmid=1 ->19:29:01 Recv: DESC_REQ PDU, fd=1027, pdubuf=0xb97a6000 ->19:29:01 Xmit: DESC PDU, fd=1027, pmid=2.0.4 end of DoDesc: sts=32 back from DoDesc: sts=32 connected=1 ->19:29:01 Recv: PROFILE PDU, fd=1027, pdubuf=0xb97a5000 ->19:29:01 Recv: FETCH PDU, fd=1027, pdubuf=0xb97a6000 ->19:29:01 Xmit: RESULT PDU, fd=1027, numpmid=1 ->19:29:01 Recv: RESULT PDU, fd=1027, pdubuf=0xb97a5000 ->19:29:01 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:29:01 End client: fd=1027 ->19:29:01 Xmit: ERROR PDU, fd=6, err=-12391: Not Connected ->19:29:01 New client: from=0.0.0.0, fd=1027, seq=9 ->19:29:06 Xmit: ERROR PDU, fd=1027, err=0: No error ->19:29:06 Recv: CREDS PDU, fd=1027, pdubuf=0xb97a6000 ->19:29:06 Recv: CREDS PDU, fd=1027, pdubuf=0x1 ->19:29:06 Recv: DESC_REQ PDU, fd=1027, pdubuf=0xb97a5000 ->19:29:06 Xmit: DESC_REQ PDU, fd=6, pmid=160.0.0 before __pmGetPDU: _pmcd_timeout=10 [Mon Mar 11 19:29:16] pmcd(9269) Warning: pduread: timeout (after 10.000 sec) while attempting to read 12 bytes out of 12 in HDR on fd=12 after __pmGetPDU: sts=-12353 ->19:29:16 Wrong PDU type: expecting DESC PDU, fd=12, got err=-12353: Timeout waiting for a response from PMCD after pmcd_trace: sts=-12366 ->19:29:16 Drop PMDA: domain=160, infd=6, outfd=12 [Mon Mar 11 19:29:16] pmcd(9269) Info: CleanupAgent ... Cleanup "dummyproc" agent (dom 160): protocol failure for fd=12, exit(0) end of DoDesc: sts=-12366 back from DoDesc: sts=-12366 connected=1 ->19:29:17 Xmit: ERROR PDU, fd=1027, err=-12366: IPC protocol failure [Mon Mar 11 19:29:17] pmcd(9269) Error: HandleClientInput: error sending Error PDU to client[1] IPC protocol failure ->19:29:17 End client: fd=1027 ->19:29:17 End client: fd=1026 [Mon Mar 11 19:29:17] pmcd(9269) Info: pmcd caught SIGTERM from pid=9657 uid=0 [Mon Mar 11 19:29:17] pmcd(9269) Info: pmcd Shutdown Log finished Mon Mar 11 19:29:17 2013