netopeer2
netopeer2 copied to clipboard
CESNET
nc_ps_poll: failed to wait for a pollsession condition (Connection timed out).
Hi, I used netopeer2-server 2.2.31 and ran it for a long time with the following error,Restart netoperer2-server, it work fine, what is the cause of the problem?
LOG：
[2025-04-15 20:21:00.079] [DBG]: LN: Session 1: Sending message:
#284
[2025-04-15 20:21:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:21:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:22:00.080] [DBG]: LN: Session 1: Sending message:
#284
[2025-04-15 20:22:00.080] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:22:00.080] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:23:00.078] [DBG]: LN: Session 1: Sending message:
#284
[2025-04-15 20:23:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:23:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:24:00.079] [DBG]: LN: Session 1: Sending message:
#284
[2025-04-15 20:24:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:24:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:24:34.902] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:34.942] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:40.102] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:40.143] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:45.302] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:45.343] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:50.503] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:50.543] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:55.703] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:24:55.743] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:00.079] [DBG]: LN: Session 1: Sending message:
#284
[2025-04-15 20:25:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:25:00.079] [DBG]: LN: Session 1: Sending message:
[2025-04-15 20:25:00.903] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:00.943] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:06.103] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:06.144] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:11.303] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:11.344] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:16.504] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:16.544] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:21.704] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:21.744] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:26.904] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:26.944] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:32.104] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:32.144] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:37.304] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:37.345] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:42.504] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:42.545] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:47.705] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:47.745] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:52.905] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:52.945] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:58.105] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). [2025-04-15 20:25:58.145] [ERR]: LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out).
What platform are you on? The error is mostly harmless but points to issues with threads and their synchronization, some threads are being starved.
What platform are you on? The error is mostly harmless but points to issues with threads and their synchronization, some threads are being starved.
I noticed that with this error (' LN: nc_ps_poll: failed to wait for a pollsession condition (Connection timed out). '), the notification worked fine at first.After running for a long time, the notification cannot be sent normally, and can only be resumed by restarting netoperer2-server.
Send notif log:
[WRN] Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 1 was not processed and will be discarded.
[2025-04-15 18:55:13.038] [warning] [yang_data_store_client.cpp:1327] YangDataStore-SYSREPO: Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 1 was not processed and will be discarded.
[WRN] Recovered "asmote-du-sensing-function" previous event "notif" ID 1 abandoned by CID 64
[WRN] Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 2 was not processed and will be discarded.
[2025-04-15 18:55:34.038] [warning] [yang_data_store_client.cpp:1327] YangDataStore-SYSREPO: Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 2 was not processed and will be discarded.
[WRN] Recovered "asmote-du-sensing-function" previous event "notif" ID 2 abandoned by CID 64
[WRN] Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 3 was not processed and will be discarded.
[2025-04-15 18:55:55.039] [warning] [yang_data_store_client.cpp:1327] YangDataStore-SYSREPO: Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 3 was not processed and will be discarded.
[WRN] Recovered "asmote-du-sensing-function" previous event "notif" ID 3 abandoned by CID 64
[WRN] Waiting for subscription of "_3gpp_common_measurements" failed, previous event "notif" ID 1 was not processed and will be discarded.
[2025-04-15 18:56:10.044] [warning] [yang_data_store_client.cpp:1327] YangDataStore-SYSREPO: Waiting for subscription of "_3gpp_common_measurements" failed, previous event "notif" ID 1 was not processed and will be discarded.
[WRN] Recovered "_3gpp_common_measurements" previous event "notif" ID 1 abandoned by CID 64
[WRN] Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 4 was not processed and will be discarded.
[2025-04-15 18:56:20.044] [warning] [yang_data_store_client.cpp:1327] YangDataStore-SYSREPO: Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 4 was not processed and will be discarded.
[WRN] Recovered "asmote-du-sensing-function" previous event "notif" ID 4 abandoned by CID 64
[WRN] Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 5 was not processed and will be discarded.
[2025-04-15 18:56:41.044] [warning] [yang_data_store_client.cpp:1327] YangDataStore-SYSREPO: Waiting for subscription of "asmote-du-sensing-function" failed, previous event "notif" ID 5 was not processed and will be discarded.
[WRN] Recovered "asmote-du-sensing-function" previous event "notif" ID 5 abandoned by CID 64
Okay, so the problem seems more serious but I have too little information to go on. My advice would be to update netopeer2 and the dependencies to the latest release, there have been lots of fixes made since the version you are using.
Okay, so the problem seems more serious but I have too little information to go on. My advice would be to update netopeer2 and the dependencies to the latest release, there have been lots of fixes made since the version you are using.
I updated to the latest version, the simulation notification constantly sent, occasionally notification timeout warning, at that time does not affect the notification continue to send. I wonder where the latest version fixes this issue, I would like to update separately.
yang_data_store_client_unittest --gtest_filter=YangDataStoreClientTest.yang_data_store_client_unittest --gtest_filter=YangDataStoreClientTest.SendNotif
Running main() from /home/yangrui/work_space/platform/build/om_framework/om_framework/third_party/googletest-release-1.12.1/googletest/src/gtest_main.cc
Note: Google Test filter = YangDataStoreClientTest.yang_data_store_client_unittest --gtest_filter=YangDataStoreClientTest.SendNotif
[==========] Running 1 test from 1 test suite.
[----------] Global test environment set-up.
[----------] 1 test from YangDataStoreClientTest
[2025-04-17 16:34:22.898][info][thread 502450] YangDataStore-SYSREPO: Connection 22 created.
[ RUN ] YangDataStoreClientTest.yang_data_store_client_unittest --gtest_filter=YangDataStoreClientTest.SendNotif
[2025-04-17 16:34:34.896][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 2839 was not processed and will be disarded.
[2025-04-17 16:34:34.897][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 2839 abandoned by CID 21
[2025-04-17 16:34:47.800][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 4345 was not processed and will be disarded.
[2025-04-17 16:34:47.800][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 4345 abandoned by CID 21
[2025-04-17 16:35:03.406][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 5848 was not processed and will be disarded.
[2025-04-17 16:35:03.406][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 5848 abandoned by CID 21
[2025-04-17 16:35:19.046][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 7351 was not processed and will be disarded.
[2025-04-17 16:35:19.046][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 7351 abandoned by CID 21
[2025-04-17 16:35:34.607][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 8861 was not processed and will be disarded.
[2025-04-17 16:35:34.608][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 8861 abandoned by CID 21
[2025-04-17 16:35:50.312][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 10371 was not processed and will be disarded.
[2025-04-17 16:35:50.312][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 10371 abandoned by CID 21
[2025-04-17 16:36:05.829][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 11874 was not processed and will be disarded.
[2025-04-17 16:36:05.829][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 11874 abandoned by CID 21
[2025-04-17 16:36:21.619][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 13384 was not processed and will be disarded.
[2025-04-17 16:36:21.619][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 13384 abandoned by CID 21
[2025-04-17 16:36:37.216][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 14894 was not processed and will be disarded.
[2025-04-17 16:36:37.216][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 14894 abandoned by CID 21
[2025-04-17 16:36:52.781][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 16397 was not processed and will be disarded.
[2025-04-17 16:36:52.781][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 16397 abandoned by CID 21
[2025-04-17 16:37:08.490][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 17907 was not processed and will be disarded.
[2025-04-17 16:37:08.490][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 17907 abandoned by CID 21
[2025-04-17 16:37:23.951][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 19410 was not processed and will be disarded.
[2025-04-17 16:37:23.951][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 19410 abandoned by CID 21
[2025-04-17 16:37:39.798][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 20920 was not processed and will be disarded.
[2025-04-17 16:37:39.798][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 20920 abandoned by CID 21
[2025-04-17 16:37:55.569][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 22430 was not processed and will be disarded.
[2025-04-17 16:37:55.569][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 22430 abandoned by CID 21
[2025-04-17 16:38:11.015][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 23933 was not processed and will be disarded.
[2025-04-17 16:38:11.015][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 23933 abandoned by CID 21
[2025-04-17 16:38:26.690][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 25443 was not processed and will be disarded.
[2025-04-17 16:38:26.690][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 25443 abandoned by CID 21
[2025-04-17 16:38:42.396][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 26953 was not processed and will be disarded.
[2025-04-17 16:38:42.396][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 26953 abandoned by CID 21
[2025-04-17 16:38:58.094][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 28456 was not processed and will be disarded.
[2025-04-17 16:38:58.094][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 28456 abandoned by CID 21
[2025-04-17 16:39:13.566][warning][thread 502450] YangDataStore-SYSREPO: Waiting for subscription of "asmote-test" failed, previous event "notif" ID 29966 was not processed and will be disarded.
[2025-04-17 16:39:13.566][warning][thread 502450] YangDataStore-SYSREPO: Recovered "asmote-test" previous event "notif" ID 29966 abandoned by CID 21
netopeer2-server -v 3 -d
[INF]: SR: Connection 21 created.
[INF]: SR: Triggering "ietf-netconf-server" "done" event on enabled data.
[INF]: LN: Listening on 0.0.0.0:830 for SSH connections.
[INF]: SR: Triggering "ietf-keystore" "done" event on enabled data.
[INF]: SR: Triggering "ietf-truststore" "done" event on enabled data.
[INF]: SR: Triggering "ietf-netconf-acm" "done" event on enabled data.
[INF]: SR: Triggering "ietf-netconf-acm" "done" event on enabled data.
[INF]: SR: Triggering "ietf-netconf-acm" "done" event on enabled data.
[INF]: SR: Triggering "ietf-netconf-acm" "done" event on enabled data.
[INF]: LN: Accepted a connection on 0.0.0.0:830 from 127.0.0.1:39612.
[WRN]: LN: SSH: ssh_packet_disconnect_callback: Received SSH_MSG_DISCONNECT: 11:Bye Bye
[ERR]: LN: Communication SSH socket unexpectedly closed.
[INF]: LN: Accepted a connection on 0.0.0.0:830 from 127.0.0.1:40644.
[INF]: LN: Received an SSH message "request-service" of subtype "ssh-userauth".
[INF]: LN: Received an SSH message "request-auth" of subtype "none".
[INF]: LN: Received an SSH message "request-auth" of subtype "interactive".
[INF]: LN: User "yangrui" authenticated.
[INF]: LN: Received an SSH message "request-channel-open" of subtype "session".
[INF]: LN: Received an SSH message "request-channel" of subtype "subsystem".
[INF]: NP: Generated new event (netconf-session-start).
[INF]: NP: Module "ietf-datastores@<any>" was requested.
[INF]: NP: Session 1: thread 1 event new RPC.
[INF]: NP: Session 1: thread 0 event new RPC.
[INF]: NP: Module "asmote-test@2024-03-28" was requested.
[INF]: NP: Session 1: thread 1 event new RPC.
[INF]: NP: Session 1: thread 2 event new RPC.
[INF]: NP: Session 1: thread 1 event new RPC.
[INF]: SR: EV LISTEN: "notif" ID 2839 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 4345 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 5848 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 7351 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 8861 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 10371 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 11874 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 13384 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 14894 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 16397 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 17907 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 19410 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 20920 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 22430 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 23933 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 25443 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 26953 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 28456 processing success (after timeout).
[INF]: SR: EV LISTEN: "notif" ID 29966 processing success (after timeout).
I cannot help you with finding the exact commit that fixed this, that is up to you. As for the server timeouts, my guess is that it takes you too long to process the generated notifications. You can increase the default netopeer2-server timeout using -t 10, for example.
Hi Micha, I'm not sure if this is the same issue: when I use "netopeer2-server -d -v 2 -t 100" error print "Waiting for subscription of "netopeer-notifications" failed, previous event "notif" ID 14960 was not processed and will be disarded." will cause the reply timeout. And I keeping pushing config and get, I will also get the errror print like that :
Jan 3 23:02:04 M2-O user.info phc2sys: [1091.753] CLOCK_REALTIME phc offset -7 s2 freq +5425 delay 800
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:04 M2-O user.info ARM: [255724.688] Packets Received by Type:
Jan 3 23:02:04 M2-O user.info ARM: [255724.688] Ant0 Ant1 Ant2 Ant3
Jan 3 23:02:04 M2-O user.info ARM: [255724.688] Pusch 0 0 0 0
Jan 3 23:02:04 M2-O user.info ARM: [255724.688] Prach 0 0 0 0
Jan 3 23:02:04 M2-O user.info ARM: [255724.688] Pdsch 0 0 0 0
Jan 3 23:02:04 M2-O user.info ARM: [255724.688] Packets tx total :0
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_interfaces] DATA FOR "ietf-interfaces" "/ietf-interfaces:interfaces" REQUESTED =======================
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-status/sync-state!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-status/supported-reference-types[item='PTP']/item!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-capability/sync-t-tsc!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/lock-state!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/two-step-flag!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/priority1!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/clock-accuracy!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/time-source!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/priority2!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_software_management] DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory/software-slot" REQUESTED =======================
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_hardware] DATA FOR "ietf-hardware" "/ietf-hardware:*" REQUESTED =======================
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_interfaces] DATA FOR "ietf-interfaces" "/ietf-interfaces:*" REQUESTED =======================
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_interfaces] DATA FOR "ietf-interfaces" "/ietf-interfaces:interfaces" REQUESTED =======================
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:04 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-status/sync-state!!
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 2 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 2 event new RPC.
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:04 M2-O user.info ptp4l: [1092.320] rms 4 max 6 freq -477 +/- 6 delay 80 +/- 0
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 2 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 2 event new RPC.
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:04 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 2 event new RPC.
Jan 3 23:02:04 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 2 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 2 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 2 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:05 M2-O user.info phc2sys: [1092.753] CLOCK_REALTIME phc offset 9 s2 freq +5439 delay 800
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:05 M2-O user.info ptp4l: [1093.228] rms 5 max 7 freq -478 +/- 8 delay 80 +/- 1
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:05 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 2 event new RPC.
Jan 3 23:02:05 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 2 event new RPC.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 2 event new RPC.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 2 event new RPC.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-capability/sync-t-tsc!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/lock-state!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/two-step-flag!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/priority1!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/clock-accuracy!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/time-source!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/priority2!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_software_management] DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory/software-slot" REQUESTED =======================
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_hardware] DATA FOR "ietf-hardware" "/ietf-hardware:*" REQUESTED =======================
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_interfaces] DATA FOR "ietf-interfaces" "/ietf-interfaces:*" REQUESTED =======================
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_interfaces] DATA FOR "ietf-interfaces" "/ietf-interfaces:interfaces" REQUESTED =======================
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]>>>TimeStamp=1970.01.03-23:02:05, 29, 100
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]>>>Writing /var/volatile/log/log_tmp.log ......
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-status/sync-state!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-status/supported-reference-types[item='PTP']/item!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/sync-capability/sync-t-tsc!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/lock-state!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/two-step-flag!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/priority1!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/clock-accuracy!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/time-source!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-sync:sync/ptp-status/sources[local-port-number=0]/priority2!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_software_management] DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory/software-slot" REQUESTED =======================
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]data_provider_cb_oran_operations :/o-ran-operations:operational-info
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/ru-instance-id!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-mplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-cusplane-version!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/declarations/supported-header-mechanism[protocol='ECPRI']/protocol!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-cause!!
Jan 3 23:02:06 M2-O user.info Listen[3313]: [Mplane]/o-ran-operations:operational-info/operational-state/restart-datetime!!
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 0 event new RPC.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 0 event new RPC.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (post edit-config).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post edit-config).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Generated new event (post get).
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (post get).
Jan 3 23:02:06 M2-O daemon.info netopeer2-server[3407]: Session 1: thread 1 event new RPC.
Jan 3 23:02:06 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Session 1: thread 1 event new RPC.
Jan 3 23:02:06 M2-O user.info phc2sys: [1093.753] CLOCK_REALTIME phc offset -8 s2 freq +5424 delay 800
Jan 3 23:02:06 M2-O user.info ptp4l: [1094.124] rms 4 max 7 freq -481 +/- 6 delay 78 +/- 1
[DHCP_PROCESS]waiting for DHCP offer
Jan 3 23:02:07 M2-O user.info phc2sys: [1094.753] CLOCK_REALTIME phc offset -14 s2 freq +5416 delay 800
[DCHP]disable current vlan
[DCHP]disable current vlan
[DCHP]create the vlan 43
[DCHP]ipv6 dhcp with vlan 43
Jan 3 23:02:07 M2-O user.info ptp4l: [1095.019] rms 4 max 7 freq -478 +/- 6 delay 78 +/- 0
Jan 3 23:02:08 M2-O user.info ARM: [255728.114] main loop is out of 2S
Jan 3 23:02:08 M2-O user.info phc2sys: [1095.753] CLOCK_REALTIME phc offset -3 s2 freq +5423 delay 800
Jan 3 23:02:08 M2-O user.info ptp4l: [1095.916] rms 4 max 7 freq -478 +/- 6 delay 79 +/- 1
Jan 3 23:02:08 M2-O user.info Listen[3313]: [Mplane]ware" "/ietf-hardware:*" REQUESTED =======================
Jan 3 23:02:08 M2-O user.info Listen[3313]: [Mplane] ==========[data_provider_cb_oran_interfaces] DATA FOR "ietf-interfaces" "/ietf-interfaces:*" REQUESTED =======================
Jan 3 23:02:09 M2-O user.info phc2sys: [1096.753] CLOCK_REALTIME phc offset 11 s2 freq +5436 delay 800
Jan 3 23:02:09 M2-O user.info ptp4l: [1096.811] rms 4 max 9 freq -479 +/- 7 delay 80 +/- 1
Jan 3 23:02:10 M2-O user.info ARM: [255730.115] Packets Received by Type:
Jan 3 23:02:10 M2-O user.info ARM: [255730.115] Ant0 Ant1 Ant2 Ant3
Jan 3 23:02:10 M2-O user.info ARM: [255730.115] Pusch 0 0 0 0
Jan 3 23:02:10 M2-O user.info ARM: [255730.115] Prach 0 0 0 0
Jan 3 23:02:10 M2-O user.info ARM: [255730.115] Pdsch 0 0 0 0
Jan 3 23:02:10 M2-O user.info ARM: [255730.115] Packets tx total :0
Jan 3 23:02:10 M2-O user.info ptp4l: [1097.708] rms 5 max 9 freq -476 +/- 8 delay 79 +/- 1
Jan 3 23:02:10 M2-O user.info phc2sys: [1097.754] CLOCK_REALTIME phc offset 0 s2 freq +5428 delay 800
[DHCP_PROCESS]waiting for DHCP offer
[DCHP]disable current vlan
[DCHP]ipv4 dhcp with none vlan
Jan 3 23:02:11 M2-O user.info ptp4l: [1098.604] rms 4 max 7 freq -478 +/- 7 delay 79 +/- 1
Jan 3 23:02:11 M2-O user.info phc2sys: [1098.754] CLOCK_REALTIME phc offset 0 s2 freq +5428 delay 800
Jan 3 23:02:12 M2-O user.info ptp4l: [1099.497] rms 4 max 7 freq -478 +/- 7 delay 80 +/- 0
Jan 3 23:02:12 M2-O user.info phc2sys: [1099.754] CLOCK_REALTIME phc offset 3 s2 freq +5431 delay 800
Jan 3 23:02:12 M2-O user.info ptp4l: [1100.393] rms 4 max 7 freq -478 +/- 7 delay 80 +/- 0
Jan 3 23:02:13 M2-O user.info phc2sys: [1100.754] CLOCK_REALTIME phc offset -2 s2 freq +5427 delay 800
[DHCP_PROCESS]waiting for DHCP offer
[DCHP]disable current vlan
[DCHP]ipv6 dhcp with none vlan
Jan 3 23:02:13 M2-O user.info ptp4l: [1101.290] rms 5 max 8 freq -477 +/- 7 delay 80 +/- 0
Jan 3 23:02:14 M2-O user.info ARM: [255734.115] After watchdog loop(every 5 times)
Jan 3 23:02:14 M2-O user.info phc2sys: [1101.754] CLOCK_REALTIME phc offset 0 s2 freq +5429 delay 800
Jan 3 23:02:14 M2-O user.info ptp4l: [1102.188] rms 5 max 10 freq -476 +/- 9 delay 79 +/- 1
Jan 3 23:02:15 M2-O user.info phc2sys: [1102.755] CLOCK_REALTIME phc offset 1 s2 freq +5430 delay 800
Jan 3 23:02:15 M2-O user.info ptp4l: [1103.084] rms 5 max 9 freq -478 +/- 8 delay 81 +/- 2
Jan 3 23:02:16 M2-O user.info ARM: [255736.115] Packets Received by Type:
Jan 3 23:02:16 M2-O user.info ARM: [255736.115] Ant0 Ant1 Ant2 Ant3
Jan 3 23:02:16 M2-O user.info ARM: [255736.115] Pusch 0 0 0 0
Jan 3 23:02:16 M2-O user.info ARM: [255736.115] Prach 0 0 0 0
Jan 3 23:02:16 M2-O user.info ARM: [255736.115] Pdsch 0 0 0 0
Jan 3 23:02:16 M2-O user.info ARM: [255736.115] Packets tx total :0
Jan 3 23:02:16 M2-O user.info phc2sys: [1103.755] CLOCK_REALTIME phc offset -2 s2 freq +5427 delay 800
Jan 3 23:02:16 M2-O daemon.warn netopeer2-server[3407]: Waiting for subscription of "netopeer-notifications" failed, previous event "notif" ID 14960 was not processed and will be disarded.
Jan 3 23:02:16 M2-O daemon.warn netopeer2-server[3407]: Recovered "netopeer-notifications" previous event "notif" ID 14960 abandoned by CID 1
Jan 3 23:02:16 M2-O daemon.info netopeer2-server[3407]: Generated new event (pre edit-config).
Jan 3 23:02:16 M2-O daemon.info netopeer2-server[3407]: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:16 M2-O user.info Listen[3316]: [Netconf][WRN]: SR: Waiting for subscription of "netopeer-notifications" failed, previous event "notif" ID 14960 was not processed and will be disarded.
Jan 3 23:02:16 M2-O user.info Listen[3316]: [Netconf][WRN]: SR: Recovered "netopeer-notifications" previous event "notif" ID 14960 abandoned by CID 1
Jan 3 23:02:16 M2-O user.info Listen[3316]: [Netconf][INF]: NP: Generated new event (pre edit-config).
Jan 3 23:02:16 M2-O user.info Listen[3316]: [Netconf][INF]: NP: edit-config error-option "stop-on-error" not supported, rollback-on-error will be performed.
Jan 3 23:02:16 M2-O user.info ptp4l: [1103.983] rms 7 max 14 freq -480 +/- 11 delay 79 +/- 1
Based on the log, it seems like the "correct" behavior, the timeout for waiting for previous notification processing cannot be customized and is set to 10 seconds. So if your subscription is not able to process it in such a long time, I suggest you change it so that it reads the notification, only stores it in some queue, and marks it as processed for sysrepo. Then you can process it whenever you can but netopeer2 does not have to wait.