The following are sample debugging sessions on the Messaging Server and Calendar Server.
Each example has three parts:
Set Environment Variables
Sample Trace Output
Short Commentary
setenv LD_LIBRARY_PATH msg-svr-base/lib/ stop-ens setenv SERVICEBUS_DEBUG 1 setenv ENS_DEBUG 1 setenv ENS_LOG_MODULES 1 setenv GAP_DEBUG 1 setenv GAP_LOG_MODULES 1 setenv XENP_TRACE 1 setenv ENS_STATS 1 msg-svr-base/bin/enpd
1 | servbus 3451633705 [26321]: Starting Service Bus 2 | servbus 3451636227 [26321]: Service Bus subscriber created successfully 3 | servbus 3451636286 [26321]: Service Bus Ready 4 | XENP -> len=36 servbus:///monitor/ens|subs|00010000 5 | XENP -> len=60 servbus:///service/ens&pid=26321 &state=running|ntfy|00000000 6 | XENP <- len=36 servbus:///monitor/ens|subs|00010000 7 | XENP <- len=4 PACK 8 | XENP <- len=60 servbus:///service/ens&pid=26321 &state=running|ntfy|00000000 9 |secs: pub: pub/s: pub/s(i): ntfy: ntfy/s :ntfy/s(i): 10 | 5 : 1: 0 : 0 : 0 : 0 : 0 : 11 |10 : 1: 0 : 0 : 0 : 0 : 0 : 12 | XENP <- len=232enp://127.0.0.1/store?evtType=NewMs&mailboxName=ServiceAdmin& timestamp=1027623669000&process=2637&hostname=ketu&numMsgs=14&size=621 &uidValidity=1025118712&imapUid=14&hdrLen=547&qUsed=16&qMax=-1& qMsgUsed=15&qMsgMax=-1|ntfy|00000000 13 | 15 : 2: 0 : 0 : 0 : 0 : 0 : 14 | 20 : 2: 0 : 0 : 0 : 0 : 0 : 15 | 25 : 2: 0 : 0 : 0 : 0 : 0 : 16 | 30 : 2: 0 : 0 : 0 : 0 : 0 : 17 | 35 : 2: 0 : 0 : 0 : 0 : 0 : 18 | 40 : 2: 0 : 0 : 0 : 0 : 0 : 19 | 45 : 2: 0 : 0 : 0 : 0 : 0 : 20 | 51 : 2: 0 : 0 : 0 : 0 : 0 : 21 | 56 : 2: 0 : 0 : 0 : 0 : 0 : 22 | 61 : 2: 0 : 0 : 0 : 0 : 0 : 23 | 66 : 2: 0 : 0 : 0 : 0 : 0 : 24 | 71 : 2: 0 : 0 : 0 : 0 : 0 : 25 | 76 : 2: 0 : 0 : 0 : 0 : 0 : 26 |secs: pub: pub/s: pub/s(i): ntfy: ntfy/s :ntfy/s(i): 27 | 81 : 2: 0 : 0 : 0 : 0 : 0 : 28 | 86 : 2: 0 : 0 : 0 : 0 : 0 : 29 | 91 : 2: 0 : 0 : 0 : 0 : 0 : 30 | 96 : 2: 0 : 0 : 0 : 0 : 0 : 31 |101: 2: 0 : 0 : 0 : 0 : 0 : 32 |106: 2: 0 : 0 : 0 : 0 : 0 : 33 |111: 2: 0 : 0 : 0 : 0 : 0 : 34 |116: 2: 0 : 0 : 0 : 0 : 0 : 35 |121: 2: 0 : 0 : 0 : 0 : 0 : 36 |126: 2: 0 : 0 : 0 : 0 : 0 : 37 |131: 2: 0 : 0 : 0 : 0 : 0 : 38 |136: 2: 0 : 0 : 0 : 0 : 0 : 39 |141: 2: 0 : 0 : 0 : 0 : 0 : 40 |146: 2: 0 : 0 : 0 : 0 : 0 : 41 |151: 2: 0 : 0 : 0 : 0 : 0 : 42 |^C 43 | XENP -> len=60 servbus:///service/ens&pid=26321 &state=stopped|ntfy|00000000 44 |servbus 3466881202 [26321]: Service Bus going away 45 |servbus 3466881542 [26321]: Failed to create subscriber- error-1
The following comments apply to the lines of the preceding trace output:
Line Number |
Comment |
1 - 8 |
Printed upon startup |
9 - 11 and 13 - 41 |
Periodic statistics print out |
12 |
A message is sent |
42 |
Control-c stopped operation. This was done to end the sample only. Not recommended for stopping processes normally. |
1 | (293 root) setenv ENS_DEBUG 99 2 | (294 root) setenv ENS_LOG_MODULES 63 3 | (295 root) msg-svr-base/bin/enpd Sample Trace Output 4 | ENS 3588422667 [26400]: LOGIN 2 5 | ENS 3588423361 [26400]: _enp_session_open_cb : new session id=2 created 6 | ENS 3588423380 [26400]: recorded new subscription : 0001; servbus:///monitor/ens 7 | ENS 3588423395 [26400]: subscribe (event=servbus:///monitor/ens, sid=2) = 0 8 | ENS 3588423403 [26400]:publish (event=servbus:///service/ens&pid=26400&state=running, sid=2) 9 | ENS 3588423414 [26400]:publish (event=servbus:///service/ens&pid=26400&state=running, sid=2) = 0 10 | ENS 3588423825 [26400]: _ens_recv_request_cb: sid=2 op=1 id=00010000 11 | ENS 3588423842 [26400]: simple|store_req (servbus:///monitor/ens#2) =2,servbus:///monitor/ens 12 | ENS 3588423848 [26400]: simple|store_evt (servbus:///monitor/ens#2) = 2,servbus:///monitor/ens 13 | ENS 3588423853 [26400]: SUBS 2 servbus:///monitor/ens 00010000 14 | ENS 3588424389 [26400]: _ens_recv_request_cb: sid=2 op=2 id=00000000 15 | ENS 3588424395 [26400]: NTFY 2 servbus:///service/ens &pid=26400&state=running 16 | ENS 3588424409 [26400]:ens_notify (event=servbus:///service/ens&pid=26400&state=running, id=00000000,sid=2):no match 17 | ENS 3588503451 [26400]: LOGIN 3 18 | ENS 3588504099 [26400]: LOGIN 4 19 | ENS 3588504938 [26400]: LOGIN 5 20 | ENS 3588505284 [26400]: LOGIN 6 21 22 | ENS 3591631839 [26400]: LOGIN 7 23 | ENS 3591637445 [26400]: _ens_recv_request_cb: sid=7 op=2 id=00000000 24 | ENS 3591637452 [26400]: NTFY 7 enp://127.0.0.1/store?evtType=NewMsg &mailboxName=ServiceAdmin×tamp=1027625056000&process=2646 &hostname=ketu&numMsgs=19&size=621&uidValidity=1025118712 &imapUid=19&hdrLen=547&qUsed=19&qMax=-1&qMsgUsed=20&qMsgMax=-1 25 | ENS 3591637467 [26400]:ens_notify (event=enp://127.0.0.1/store?evtType=NewMsg &mailboxName=ServiceAdmin×tamp=1027625056000&process=2646 &hostname=ketu&numMsgs=19&size=621&uidValidity=1025118712 &imapUid=19&hdrLen=547&qUsed=19&qMax=-1&qMsgUsed=20 &qMsgMax=-1, id=00000000, sid=7): no match 26 | 27 | ENS 3595049771 [26400]: session closing 7 28 | ^CENS 3596193757 [26400]:publish (event=servbus:///service/ens&pid=26400&state=stopped, sid=2) 29 | ENS 3596193782 [26400]:publish (event=servbus:///service/ens&pid=26400&state=stopped, sid=2) = 0 30 | ENS 3596193987 [26400]: pas_dispatcher_delete : clean up starting 31 | ENS 3596194018 [26400]: _enp_session_closing_cb : closing session id=2 32 | ENS 3596194024 [26400]: destroying subscription :0001; servbus:///monitor/ens 33 | ENS 3596194041 [26400]: pas_dispatcher_delete : 0 client(s) have been bumped 34 | ENS 3596194065 [26400]: session closing 2 35 | ENS 3596194075 [26400]: simple|remov_evt (2, servbus:///monitor/ens) 36 | ENS 3596194107 [26400]: session closing 3 37 | ENS 3596194216 [26400]: session closing 4 38 | ENS 3596194281 [26400]: session closing 5 39 | ENS 3596195039 [26400]: session closing 6
The following comments apply to the lines of the preceding trace output:
Line Number |
Comment |
1 - 20 |
Initialization |
22-26 |
Sent email message |
27 |
Printed asynchronously |
28 |
Control-c stopped operation. This was done to end the sample only. Not recommended for stopping processes normally. |
29-39 |
enpd exiting |