search

CESNET / netopeer2

NETCONF toolset
BSD 3-Clause "New" or "Revised" License
300 stars 189 forks source link

ietf-netconf-notifications event "notif" was not processed. #1530

Closed Krisscut closed 8 months ago

Krisscut commented 8 months ago

Versions: Netopeer2-server: netopeer2-2.1.71 sysrepo: sysrepo-2.2.105 libnetconf2: libnetconf2-2.1.37 libyang: libyang-2.1.111

I'm having an issue with the notification, with the error message [ERR]: SR: Waiting for subscription of "ietf-netconf-notifications" failed, previous event "notif" ID 2 was not processed. It occurs sporadically, like in 1 run out of 50.

We have an app "CallHomeManager" which load the listen.xml & callhome.xml in sysrepo using the command line, and it triggers the ietf-netconf-notifications Between the passed & failed logs below, the difference is that in the log UE_APP-OAM/sysrepo, EV LISTEN: "ietf-netconf-notifications" "notif" ID 2 priority 0 success., the remaining subscribers is 1 when failing and 0 in working case. So I guess something is stuck not answering but can this be investigated ? I don't see anything in the log about the other subscribers not answering in time.

Maybe by dumping the sysrepo state and checking the connection id, process id ?

also one thing that puzzles me is why we have 4 subscribers on the ietf-netconf-notifications when we actually only have one app on it and it answers just fine (orusw). Could it be netopeer2-server that doesn't handle process it ?

Failing log:

2024-01-30 01:21:17.346 DEBUG/UE_APP-OAM/CallHomeManager: CallHomeManager.py:133 - fill_jinja_xml_with_context() - Input template file /opt/UE_APP_OAM/service/CallHomeManager/listen.xml

2024-01-30 01:21:17,346 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:21:17.346 INFO/UE_APP-OAM/CallHomeManager: CallHomeManager.py:140 - fill_jinja_xml_with_context() - Generated file /opt/UE_APP_OAM/service/CallHomeManager/xmlListenTree.xml !

2024-01-30 01:21:17,445 DEBG 'CallHomeManager' stderr output: [INF] Connection 47 created.

2024-01-30 01:21:17,446 DEBG 'CallHomeManager' stderr output: [INF] Session 79 (user "root", CID 47) created.

2024-01-30 01:21:17,449 DEBG 'CallHomeManager' stderr output: [INF] EV ORIGIN: "ietf-netconf-server" "done" ID 1 priority 0 for 23 subscribers published.

2024-01-30 01:21:17,451 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "ietf-netconf-server" "done" ID 1 priority 0 processing (remaining 23 subscribers). [ERR]: LN: Could not bind "192.168.134.2" port 830 (Address already in use). [INF]: SR: EV LISTEN: "ietf-netconf-server" "done" ID 1 priority 0 success (remaining 0 subscribers).

2024-01-30 01:21:17,452 DEBG 'CallHomeManager' stderr output: [INF] EV ORIGIN: "ietf-netconf-server" "done" ID 1 priority 0 succeeded.

2024-01-30 01:21:17,453 DEBG 'CallHomeManager' stderr output: [INF] EV ORIGIN: "ietf-netconf-notifications" "notif" ID 2 priority 0 for 4 subscribers published.

2024-01-30 01:21:17,454 DEBG 'orusw' stdout output: 2024-01-30 01:21:17/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "ietf-netconf-notifications" "notif" ID 2 processing. 2024-01-30 01:21:17/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "ietf-netconf-notifications" "notif" ID 2 priority 0 success (remaining 1 subscribers).

2024-01-30 01:21:17,470 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:21:17.470 DEBUG/UE_APP-OAM/CallHomeManager: CallHomeManager.py:195 - handleSshConnection() - [MPlaneLink=1] Smo Client received from redis: [{'ip': '192.168.216.2', 'port': 4334}]

2024-01-30 01:21:17,472 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:21:17.472 DEBUG/UE_APP-OAM/CallHomeManager: CallHomeManager.py:133 - fill_jinja_xml_with_context() - Input template file /opt/UE_APP_OAM/service/CallHomeManager/callHome.xml

2024-01-30 01:21:17,473 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:21:17.472 INFO/UE_APP-OAM/CallHomeManager: CallHomeManager.py:140 - fill_jinja_xml_with_context() - Generated file /opt/UE_APP_OAM/service/CallHomeManager/xmlCallHomeTree.xml !

2024-01-30 01:21:17,572 DEBG 'CallHomeManager' stderr output: [INF] Connection 48 created.

2024-01-30 01:21:17,572 DEBG 'CallHomeManager' stderr output: [INF] Session 83 (user "root", CID 48) created.

2024-01-30 01:21:17,576 DEBG 'CallHomeManager' stderr output: [INF] EV ORIGIN: "ietf-netconf-server" "done" ID 2 priority 0 for 23 subscribers published.

2024-01-30 01:21:17,576 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "ietf-netconf-server" "done" ID 2 priority 0 processing (remaining 23 subscribers).

2024-01-30 01:21:17,577 DEBG 'netopeer2-server' stdout output: [ERR]: LN: Unable to use Keyboard-Interactive authentication method without setting the name of the PAM configuration file first.

2024-01-30 01:21:17,577 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "ietf-netconf-server" "done" ID 2 priority 0 success (remaining 0 subscribers). [INF]: LN: Call Home client "NC-Client-1" endpoint "default-ssh" connecting... [INF]: LN: Trying to connect via IPv4 to 192.168.216.2:4334.

2024-01-30 01:21:17,577 DEBG 'CallHomeManager' stderr output: [INF] EV ORIGIN: "ietf-netconf-server" "done" ID 2 priority 0 succeeded.

2024-01-30 01:21:17,578 DEBG 'netopeer2-server' stdout output: [INF]: LN: Successfully connected to 192.168.216.2:4334 over IPv4.

2024-01-30 01:21:17,579 DEBG 'netopeer2-server' stdout output: [INF]: SR: Session 85 (user "root", CID 39) created.

2024-01-30 01:21:18,318 DEBG 'netopeer2-server' stdout output: [INF]: LN: Received an SSH message "request-service" of subtype "ssh-userauth".

2024-01-30 01:21:18,320 DEBG 'netopeer2-server' stdout output: [INF]: LN: Received an SSH message "request-auth" of subtype "none".

2024-01-30 01:21:18,324 DEBG 'netopeer2-server' stdout output: [INF]: LN: Received an SSH message "request-auth" of subtype "password". [INF]: LN: User "docomo999" authenticated.

2024-01-30 01:21:18,326 DEBG 'netopeer2-server' stdout output: [INF]: LN: Received an SSH message "request-channel-open" of subtype "session".

2024-01-30 01:21:18,328 DEBG 'netopeer2-server' stdout output: [INF]: LN: Received an SSH message "request-channel" of subtype "subsystem".

2024-01-30 01:21:18,332 DEBG 'netopeer2-server' stdout output: [INF]: LN: Call Home client "NC-Client-1" session 1 established. [INF]: SR: Session 86 (user "root", CID 39) created.

2024-01-30 01:21:18,432 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 1 priority 0 for 1 subscribers published.

2024-01-30 01:21:18,433 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 1 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "ietf-datastores@" was requested.

2024-01-30 01:21:18,433 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 1 priority 0 success (remaining 0 subscribers). [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 1 priority 0 succeeded.

2024-01-30 01:21:18,434 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 0 event new RPC.

2024-01-30 01:21:18,437 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 2 priority 0 for 1 subscribers published.

2024-01-30 01:21:18,453 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 2 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "ietf-datastores@" was requested. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 2 priority 0 success (remaining 0 subscribers). [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 2 priority 0 succeeded. [INF]: NP: Session 1: thread 2 event new RPC.

2024-01-30 01:21:18,458 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 3 priority 0 for 1 subscribers published. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 3 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "ietf-netconf-nmda@" was requested. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 3 priority 0 success (remaining 0 subscribers). [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 3 priority 0 succeeded. [INF]: NP: Session 1: thread 0 event new RPC.

2024-01-30 01:21:18,481 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 4 priority 0 for 1 subscribers published.

2024-01-30 01:21:18,481 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 4 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "ietf-origin@" was requested.

2024-01-30 01:21:18,489 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 4 priority 0 success (remaining 0 subscribers).

2024-01-30 01:21:18,489 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 4 priority 0 succeeded.

2024-01-30 01:21:18,491 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 1 event new RPC.

2024-01-30 01:21:18,496 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 1 priority 0 for 1 subscribers published.

2024-01-30 01:21:18,499 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 1 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:18,707 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 1 priority 0 success (remaining 0 subscribers).

2024-01-30 01:21:18,712 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 1 priority 0 succeeded.

2024-01-30 01:21:18,718 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 2 event new RPC.

2024-01-30 01:21:18,889 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 5 priority 0 for 1 subscribers published.

2024-01-30 01:21:18,893 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 5 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "o-ran-wg4-features@" was requested. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 5 priority 0 fail (remaining 1 subscribers). [ERR]: SR: NETCONF error occurred. [WRN]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 5 priority 0 failed (Invalid argument). [ERR]: SR: User callback failed. [ERR]: NP: Failed to send an RPC (User callback failed).

2024-01-30 01:21:18,895 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 0 event new RPC. [INF]: NP: Session 1: thread 0 event reply error.

2024-01-30 01:21:18,952 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 6 priority 0 for 1 subscribers published.

2024-01-30 01:21:18,952 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 6 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "o-ran-wg4-features@" was requested.

2024-01-30 01:21:18,953 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 6 priority 0 fail (remaining 1 subscribers).

2024-01-30 01:21:18,954 DEBG 'netopeer2-server' stdout output: [ERR]: SR: NETCONF error occurred.

2024-01-30 01:21:18,955 DEBG 'netopeer2-server' stdout output: [WRN]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 6 priority 0 failed (Invalid argument).

2024-01-30 01:21:18,957 DEBG 'netopeer2-server' stdout output: [ERR]: SR: User callback failed. [ERR]: NP: Failed to send an RPC (User callback failed). [INF]: NP: Session 1: thread 0 event new RPC. [INF]: NP: Session 1: thread 0 event reply error.

2024-01-30 01:21:18,976 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 7 priority 0 for 1 subscribers published. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 7 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "o-ran-wg4-features@" was requested. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 7 priority 0 fail (remaining 1 subscribers). [ERR]: SR: NETCONF error occurred. [WRN]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 7 priority 0 failed (Invalid argument). [ERR]: SR: User callback failed. [ERR]: NP: Failed to send an RPC (User callback failed). [INF]: NP: Session 1: thread 1 event new RPC. [INF]: NP: Session 1: thread 1 event reply error.

2024-01-30 01:21:19,010 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 8 priority 0 for 1 subscribers published. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 8 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "o-ran-wg4-features@" was requested. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 8 priority 0 fail (remaining 1 subscribers). [ERR]: SR: NETCONF error occurred. [WRN]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 8 priority 0 failed (Invalid argument). [ERR]: SR: User callback failed. [ERR]: NP: Failed to send an RPC (User callback failed). [INF]: NP: Session 1: thread 0 event new RPC. [INF]: NP: Session 1: thread 0 event reply error.

2024-01-30 01:21:19,083 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 9 priority 0 for 1 subscribers published.

2024-01-30 01:21:19,084 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 9 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:19,085 DEBG 'netopeer2-server' stdout output: [INF]: NP: Module "o-ran-wg4-features@" was requested.

2024-01-30 01:21:19,086 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 9 priority 0 fail (remaining 1 subscribers).

2024-01-30 01:21:19,088 DEBG 'netopeer2-server' stdout output: [ERR]: SR: NETCONF error occurred.

2024-01-30 01:21:19,089 DEBG 'netopeer2-server' stdout output: [WRN]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 9 priority 0 failed (Invalid argument).

2024-01-30 01:21:19,090 DEBG 'netopeer2-server' stdout output: [ERR]: SR: User callback failed.

2024-01-30 01:21:19,090 DEBG 'netopeer2-server' stdout output: [ERR]: NP: Failed to send an RPC (User callback failed).

2024-01-30 01:21:19,091 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 1 event new RPC.

2024-01-30 01:21:19,092 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 1 event reply error.

2024-01-30 01:21:19,093 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 10 priority 0 for 1 subscribers published.

2024-01-30 01:21:19,095 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 10 priority 0 processing (remaining 1 subscribers). [INF]: NP: Module "o-ran-wg4-features@" was requested.

2024-01-30 01:21:19,096 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 10 priority 0 fail (remaining 1 subscribers). [ERR]: SR: NETCONF error occurred. [WRN]: SR: EV ORIGIN: "/ietf-netconf-monitoring:get-schema" "rpc" ID 10 priority 0 failed (Invalid argument). [ERR]: SR: User callback failed. [ERR]: NP: Failed to send an RPC (User callback failed).

2024-01-30 01:21:19,096 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 0 event new RPC. [INF]: NP: Session 1: thread 0 event reply error.

2024-01-30 01:21:19,412 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 2 priority 0 for 1 subscribers published.

2024-01-30 01:21:19,413 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 2 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:19,415 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,416 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-operations.c:329 ========== DATA FOR "o-ran-operations" "/o-ran-operations:operational-info" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 1 processing success.

2024-01-30 01:21:19,416 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,417 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 2 priority 0 success (remaining 0 subscribers).

2024-01-30 01:21:19,417 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 2 priority 0 succeeded.

2024-01-30 01:21:19,418 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 2 event new RPC.

2024-01-30 01:21:19,421 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 1 processing success.

2024-01-30 01:21:19,421 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 3 priority 0 for 1 subscribers published. [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 3 priority 0 processing (remaining 1 subscribers). [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,422 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 2 published.

2024-01-30 01:21:19,422 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 2 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED =======================

2024-01-30 01:21:19,423 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1

2024-01-30 01:21:19,423 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2

2024-01-30 01:21:19,423 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 2 processing success.

2024-01-30 01:21:19,423 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 2 succeeded.

2024-01-30 01:21:19,424 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 3 published.

2024-01-30 01:21:19,425 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 3 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED =======================

2024-01-30 01:21:19,425 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1

2024-01-30 01:21:19,425 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2

2024-01-30 01:21:19,427 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 3 processing success.

2024-01-30 01:21:19,428 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 3 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 4 published.

2024-01-30 01:21:19,428 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 4 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1

2024-01-30 01:21:19,428 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2

2024-01-30 01:21:19,429 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 4 processing success.

2024-01-30 01:21:19,430 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 4 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 5 published.

2024-01-30 01:21:19,434 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 5 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1

2024-01-30 01:21:19,436 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 5 processing success.

2024-01-30 01:21:19,438 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 5 succeeded.

2024-01-30 01:21:19,440 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 6 published.

2024-01-30 01:21:19,442 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 6 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 6 processing success.

2024-01-30 01:21:19,443 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 6 succeeded. [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 3 priority 0 success (remaining 0 subscribers).

2024-01-30 01:21:19,444 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 3 priority 0 succeeded.

2024-01-30 01:21:19,444 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 2 event new RPC.

2024-01-30 01:21:19,446 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 4 priority 0 for 1 subscribers published.

2024-01-30 01:21:19,446 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 4 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:19,455 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 3 published.

2024-01-30 01:21:19,456 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 3 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-hardware.c:837 ========== DATA FOR "ietf-hardware" "/ietf-hardware:hardware" REQUESTED =======================

2024-01-30 01:21:19,457 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 3 processing success.

2024-01-30 01:21:19,457 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 3 succeeded.

2024-01-30 01:21:19,461 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 4 published.

2024-01-30 01:21:19,463 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 4 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-hardware.c:837 ========== DATA FOR "ietf-hardware" "/ietf-hardware:hardware" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 4 processing success.

2024-01-30 01:21:19,463 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 4 succeeded.

2024-01-30 01:21:19,467 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 5 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-hardware.c:837 ========== DATA FOR "ietf-hardware" "/ietf-hardware:hardware" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 5 processing success.

2024-01-30 01:21:19,467 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 5 published. [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 5 succeeded. [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 6 published.

2024-01-30 01:21:19,469 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 6 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-hardware.c:837 ========== DATA FOR "ietf-hardware" "/ietf-hardware:hardware" REQUESTED =======================

2024-01-30 01:21:19,469 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 6 processing success.

2024-01-30 01:21:19,470 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 6 succeeded.

2024-01-30 01:21:19,477 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 7 published.

2024-01-30 01:21:19,481 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 7 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-hardware.c:837 ========== DATA FOR "ietf-hardware" "/ietf-hardware:hardware" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 7 processing success.

2024-01-30 01:21:19,481 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 7 succeeded. [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 4 priority 0 success (remaining 0 subscribers).

2024-01-30 01:21:19,484 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 4 priority 0 succeeded. [INF]: NP: Session 1: thread 2 event new RPC.

2024-01-30 01:21:19,512 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 5 priority 0 for 1 subscribers published.

2024-01-30 01:21:19,514 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 5 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:19,616 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 2 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-operations.c:329 ========== DATA FOR "o-ran-operations" "/o-ran-operations:operational-info" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 2 processing success.

2024-01-30 01:21:19,617 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 2 published. [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 2 succeeded.

2024-01-30 01:21:19,618 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 3 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-operations.c:329 ========== DATA FOR "o-ran-operations" "/o-ran-operations:operational-info" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 3 processing success.

2024-01-30 01:21:19,618 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 3 published. [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 3 succeeded.

2024-01-30 01:21:19,620 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 5 priority 0 success (remaining 0 subscribers). [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 5 priority 0 succeeded. [INF]: NP: Session 1: thread 2 event new RPC.

2024-01-30 01:21:19,621 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 6 priority 0 for 1 subscribers published.

2024-01-30 01:21:19,622 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 6 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:19,751 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-acm:nacm/denied-operations" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/ietf-netconf-acm:nacm/denied-operations" "oper get" ID 1 processing.

2024-01-30 01:21:19,752 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 1 processing.

2024-01-30 01:21:19,761 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 1 processing success. [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV LISTEN: "/ietf-netconf-acm:nacm/denied-operations" "oper get" ID 1 processing success. [INF]: SR: EV ORIGIN: "/ietf-netconf-acm:nacm/denied-operations" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,761 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-acm:nacm/denied-data-writes" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/ietf-netconf-acm:nacm/denied-data-writes" "oper get" ID 1 processing.

2024-01-30 01:21:19,762 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 2 published.

2024-01-30 01:21:19,762 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 2 processing. [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 2 processing success.

2024-01-30 01:21:19,762 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 2 succeeded. [INF]: SR: EV LISTEN: "/ietf-netconf-acm:nacm/denied-data-writes" "oper get" ID 1 processing success.

2024-01-30 01:21:19,762 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-acm:nacm/denied-data-writes" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,763 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-acm:nacm/denied-notifications" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/ietf-netconf-acm:nacm/denied-notifications" "oper get" ID 1 processing.

2024-01-30 01:21:19,763 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 3 published.

2024-01-30 01:21:19,763 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 3 processing. [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 3 processing success.

2024-01-30 01:21:19,763 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 3 succeeded.

2024-01-30 01:21:19,764 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-acm:nacm/denied-notifications" "oper get" ID 1 processing success. [INF]: SR: EV ORIGIN: "/ietf-netconf-acm:nacm/denied-notifications" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,778 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 4 published. [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 4 processing.

2024-01-30 01:21:19,786 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" ID 4 processing success. [INF]: SR: EV ORIGIN: "/sysrepo-monitoring:sysrepo-state/connection[cid='39']/nacm-stats" "oper get" index 0 ID 4 succeeded. [INF]: SR: EV ORIGIN: "/nc-notifications:netconf" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,787 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/nc-notifications:netconf" "oper get" ID 1 processing.

2024-01-30 01:21:19,788 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/nc-notifications:netconf" "oper get" ID 1 processing success.

2024-01-30 01:21:19,789 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/nc-notifications:netconf" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,789 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-interfaces:interfaces" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,791 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-interfaces:interfaces" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-interfaces.c:751 ========== DATA FOR "ietf-interfaces" "/ietf-interfaces:interfaces" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-interfaces.c:756 Printing static data: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-interfaces:interfaces" "oper get" ID 1 processing success.

2024-01-30 01:21:19,791 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-interfaces:interfaces" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,805 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-subscribed-notifications:streams" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/ietf-subscribed-notifications:streams" "oper get" ID 1 processing. [INF]: SR: EV LISTEN: "/ietf-subscribed-notifications:streams" "oper get" ID 1 processing success. [INF]: SR: EV ORIGIN: "/ietf-subscribed-notifications:streams" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/ietf-subscribed-notifications:subscriptions" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/ietf-subscribed-notifications:subscriptions" "oper get" ID 1 processing. [INF]: SR: EV LISTEN: "/ietf-subscribed-notifications:subscriptions" "oper get" ID 1 processing success.

2024-01-30 01:21:19,807 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-subscribed-notifications:subscriptions" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:netconf-state" "oper get" index 0 ID 1 published. [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:netconf-state" "oper get" ID 1 processing.

2024-01-30 01:21:19,811 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf-monitoring:netconf-state" "oper get" ID 1 processing success.

2024-01-30 01:21:19,811 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:netconf-state" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,816 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:netconf-state/ietf-netconf-monitoring:capabilities" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,817 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-netconf-monitoring:netconf-state/ietf-netconf-monitoring:capabilities" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-netconf-monitoring:netconf-state/ietf-netconf-monitoring:capabilities" "oper get" ID 1 processing success.

2024-01-30 01:21:19,820 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 8 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-hardware.c:837 ========== DATA FOR "ietf-hardware" "/ietf-hardware:hardware" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/ietf-hardware:hardware" "oper get" ID 8 processing success.

2024-01-30 01:21:19,820 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/ietf-netconf-monitoring:netconf-state/ietf-netconf-monitoring:capabilities" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 8 published. [INF]: SR: EV ORIGIN: "/ietf-hardware:hardware" "oper get" index 0 ID 8 succeeded.

2024-01-30 01:21:19,820 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-sync:sync" "oper get" index 0 ID 4 published.

2024-01-30 01:21:19,821 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-sync:sync" "oper get" ID 4 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-sync.c:684 ========== DATA FOR "o-ran-sync" "/o-ran-sync:sync" REQ #4 =======================

2024-01-30 01:21:19,822 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-sync:sync" "oper get" ID 4 processing success.

2024-01-30 01:21:19,822 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-sync:sync" "oper get" index 0 ID 4 succeeded.

2024-01-30 01:21:19,823 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-dhcp:dhcp" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,823 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-dhcp:dhcp" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-dhcp.c:2337 ========== DATA FOR "o-ran-dhcp" "/o-ran-dhcp:dhcp" REQ #1 ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-interfaces.c:1031 waiting for dhcp_info.dhcp_version ...

2024-01-30 01:21:19,824 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/ERR:UE_APP-OAM/orusw[RU01]: airphone-orusim/common/src/libyang-utils.c:226 Invalid argument : NULL pointer. 2024-01-30 01:21:19/ERR:UE_APP-OAM/orusw[RU01]: airphone-orusim/common/src/libyang-utils.c:236 Invalid argument : NULL pointer. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-interfaces.c:1031 waiting for dhcp_info.dhcp_version ... 2024-01-30 01:21:19/ERR:UE_APP-OAM/orusw[RU01]: airphone-orusim/common/src/libyang-utils.c:226 Invalid argument : NULL pointer. 2024-01-30 01:21:19/ERR:UE_APP-OAM/orusw[RU01]: airphone-orusim/common/src/libyang-utils.c:236 Invalid argument : NULL pointer. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-dhcp.c:2462 waiting for dhcp_info.dhcp_version ... 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/common/src/libyang-utils.c:869

(null)

2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-dhcp:dhcp" "oper get" ID 1 processing success.

2024-01-30 01:21:19,824 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-dhcp:dhcp" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,824 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-externalio:external-io" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,828 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-externalio:external-io" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-external-io.c:130 ========== DATA FOR "o-ran-externalio" "/o-ran-externalio:external-io" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-external-io.c:77 Start reporting oper data for externalio... 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-external-io.c:47 Initializing inputs in externalio 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-external-io.c:54 Initializing outputs in externalio 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-externalio:external-io" "oper get" ID 1 processing success. 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-mplane-int:mplane-info" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-mplane-int.c:176 ========== DATA FOR "o-ran-mplane-int" "/o-ran-mplane-int:mplane-info" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-mplane-int:mplane-info" "oper get" ID 1 processing success. 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-ald-port:ald-ports-io" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-ald-port.c:86 ========== DATA FOR "o-ran-ald-port" "/o-ran-ald-port:ald-ports-io" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-ald-port:ald-ports-io" "oper get" ID 1 processing success.

2024-01-30 01:21:19,828 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-externalio:external-io" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-mplane-int:mplane-info" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-mplane-int:mplane-info" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-ald-port:ald-ports-io" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-ald-port:ald-ports-io" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,829 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-transceiver:port-transceivers" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-transceiver.c:420 ========== DATA FOR "o-ran-transceiver" "/o-ran-transceiver:port-transceivers" REQUESTED =======================

2024-01-30 01:21:19,829 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-transceiver:port-transceivers" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,829 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-transceiver:port-transceivers" "oper get" ID 1 processing success.

2024-01-30 01:21:19,829 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-transceiver:port-transceivers" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,830 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 4 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-operations.c:329 ========== DATA FOR "o-ran-operations" "/o-ran-operations:operational-info" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-operations:operational-info" "oper get" ID 4 processing success.

2024-01-30 01:21:19,830 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 4 published. [INF]: SR: EV ORIGIN: "/o-ran-operations:operational-info" "oper get" index 0 ID 4 succeeded.

2024-01-30 01:21:19,831 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 7 published.

2024-01-30 01:21:19,831 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 7 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:1019 ========== DATA FOR "o-ran-software-management" "/o-ran-software-management:software-inventory" REQUESTED =======================

2024-01-30 01:21:19,831 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-1 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-software-management.c:990 Number of files 2 in SW-COMPONENT-2

2024-01-30 01:21:19,834 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-software-management:software-inventory" "oper get" ID 7 processing success. 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-fan:fan-tray" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-fan.c:50 ========== DATA FOR "o-ran-fan" "/o-ran-fan:fan-tray" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-fan:fan-tray" "oper get" ID 1 processing success.

2024-01-30 01:21:19,834 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-software-management:software-inventory" "oper get" index 0 ID 7 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-fan:fan-tray" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-fan:fan-tray" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-fm:active-alarm-list" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,835 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-fm:active-alarm-list" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-fm.c:294 ========== DATA FOR "o-ran-fm" "/o-ran-fm:active-alarm-list" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-fm.c:305 o-ran-fm get starting loop 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-fm.c:320 o-ran-fm get ending loop

2024-01-30 01:21:19,840 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-fm.c:325 o-ran-fm get done 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-fm:active-alarm-list" "oper get" ID 1 processing success. 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-lbm:md-data-definitions" "oper get" ID 1 processing. 2024-01-30 01:21:19/DBG:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-lbm.c:323 ========== DATA FOR "o-ran-lbm" "/o-ran-lbm:md-data-definitions" REQUESTED ======================= [ERR] No data found for "/o-ran-lbm:md-data-definitions/maintenance-domain[1]/maintenance-association[1]/component-list[1]/maintenance-association-end-point[1]". 2024-01-30 01:21:19/ERR:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, No data found for "/o-ran-lbm:md-data-definitions/maintenance-domain[1]/maintenance-association[1]/component-list[1]/maintenance-association-end-point[1]". 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-lbm:md-data-definitions" "oper get" ID 1 processing success. 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-performance-management:performance-measurement-objects" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-pm.c:1427 ========== DATA FOR "o-ran-performance-management" "/o-ran-performance-management:performance-measurement-objects" REQUESTED ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-performance-management:performance-measurement-objects" "oper get" ID 1 processing success.

2024-01-30 01:21:19,840 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-fm:active-alarm-list" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-lbm:md-data-definitions" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-lbm:md-data-definitions" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-performance-management:performance-measurement-objects" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-performance-management:performance-measurement-objects" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,840 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-module-cap:module-capability" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,841 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-module-cap:module-capability" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-module-cap.c:99 ========== DATA FOR "o-ran-module-cap" "/o-ran-module-cap:module-capability" REQUESTED =======================

2024-01-30 01:21:19,841 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-module-cap:module-capability" "oper get" ID 1 processing success.

2024-01-30 01:21:19,841 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-module-cap:module-capability" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,842 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-delay-management:delay-management" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,842 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-delay-management:delay-management" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-delay-management.c:73 ========== DATA FOR "o-ran-delay-management" "/o-ran-delay-management:delay-management" REQUESTED =======================

2024-01-30 01:21:19,843 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-delay-management:delay-management" "oper get" ID 1 processing success.

2024-01-30 01:21:19,843 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-delay-management:delay-management" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,845 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-uplane-conf:user-plane-configuration" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-uplane-conf.c:525 ========== DATA FOR "o-ran-uplane-conf" "/o-ran-uplane-conf:user-plane-configuration" REQUESTED (SUB ID = 138 REQ ID = 1) ======================= 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-uplane-conf:user-plane-configuration" "oper get" ID 1 processing success.

2024-01-30 01:21:19,847 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-uplane-conf:user-plane-configuration" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-uplane-conf:user-plane-configuration" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,848 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-uplane-conf:user-plane-configuration/tx-array-carriers" "oper get" index 0 ID 1 published.

2024-01-30 01:21:19,851 DEBG 'oranManager' stdout output: 2024-01-30 01:21:19.848 INFO/UE_APP-OAM/OranManager: OranManager.py:106 - callbackUplaneConfGetOperationalTx() - In get operationalTX: /*

2024-01-30 01:21:19,858 DEBG 'oranManager' stdout output: 2024-01-30 01:21:19.854 INFO/UE_APP-OAM/OranManager: OranManager.py:115 - callbackUplaneConfGetOperationalTx() - Returning info for 0 tx_arrays 2024-01-30 01:21:19.855 INFO/UE_APP-OAM/OranManager: OranManager.py:92 - callbackUplaneConfGetOperationalRx() - In get operationalRX: /*

2024-01-30 01:21:19,859 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-uplane-conf:user-plane-configuration/tx-array-carriers" "oper get" index 0 ID 1 succeeded. [INF]: SR: EV ORIGIN: "/o-ran-uplane-conf:user-plane-configuration/rx-array-carriers" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-uplane-conf:user-plane-configuration/rx-array-carriers" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,861 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-beamforming:beamforming-config" "oper get" ID 1 processing. 2024-01-30 01:21:19/INF:UE_APP-OAM/orusw[RU01]: airphone-orusim/orusw/o-ran-beamforming.c:74 ========== DATA FOR "o-ran-beamforming" "/o-ran-beamforming:beamforming-config" REQUESTED =======================

2024-01-30 01:21:19,862 DEBG 'oranManager' stdout output: 2024-01-30 01:21:19.858 INFO/UE_APP-OAM/OranManager: OranManager.py:101 - callbackUplaneConfGetOperationalRx() - Returning info for 0 rx_arrays

2024-01-30 01:21:19,862 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/o-ran-beamforming:beamforming-config" "oper get" index 0 ID 1 published. [INF]: SR: EV ORIGIN: "/o-ran-beamforming:beamforming-config" "oper get" index 0 ID 1 succeeded.

2024-01-30 01:21:19,863 DEBG 'orusw' stdout output: 2024-01-30 01:21:19/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "/o-ran-beamforming:beamforming-config" "oper get" ID 1 processing success.

2024-01-30 01:21:19,906 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "/ietf-netconf:get" "rpc" ID 6 priority 0 success (remaining 0 subscribers). [INF]: SR: EV ORIGIN: "/ietf-netconf:get" "rpc" ID 6 priority 0 succeeded.

2024-01-30 01:21:19,957 DEBG 'netopeer2-server' stdout output: [INF]: NP: Session 1: thread 0 event new RPC.

2024-01-30 01:21:20,090 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV ORIGIN: "/notifications:create-subscription" "rpc" ID 1 priority 0 for 1 subscribers published. [INF]: SR: EV LISTEN: "/notifications:create-subscription" "rpc" ID 1 priority 0 processing (remaining 1 subscribers).

2024-01-30 01:21:20,483 DEBG 'LinkManagerOru' stderr output: XMT: Forming Solicit, 7740 ms elapsed. XMT: X-- IA_NA ac:13:00:02 XMT: | X-- Request renew in +3600 XMT: | X-- Request rebind in +5400 XMT: Solicit on eth0.2, interval 8980ms.

2024-01-30 01:21:27,579 DEBG 'CallHomeManager' stderr output: [ERR] Waiting for subscription of "ietf-netconf-notifications" failed, previous event "notif" ID 2 was not processed.

2024-01-30 01:21:27,580 DEBG 'CallHomeManager' stderr output: [ERR] Failed to generate netconf-config-change notification, but changes were applied. sysrepocfg error: Failed to merge edit data (Timeout expired)

2024-01-30 01:21:27,595 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:21:27.595 INFO/UE_APP-OAM/CallHomeManager: CallHomeManager.py:173 - handleLinkManagerData() - [MPlaneLink=1] Replying to validation dn=Validation/1/LINK/SRV with data = {'status': 'OK', 'message': 'No error'}

2024-01-30 01:21:27,596 DEBG 'LinkManagerOru' stderr output:

Working log:

2024-01-30 01:18:23,765 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:18:23.765 DEBUG/UE_APP-OAM/CallHomeManager: CallHomeManager.py:133 - fill_jinja_xml_with_context() - Input template file /opt/UE_APP_OAM/service/CallHomeManager/callHome.xml

2024-01-30 01:18:23,766 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:18:23.766 INFO/UE_APP-OAM/CallHomeManager: CallHomeManager.py:140 - fill_jinja_xml_with_context() - Generated file /opt/UE_APP_OAM/service/CallHomeManager/xmlCallHomeTree.xml !

2024-01-30 01:18:23,863 DEBG 'CallHomeManager' stderr output: [INF] Connection 48 created.

2024-01-30 01:18:23,865 DEBG 'CallHomeManager' stderr output: [INF] Session 79 (user "root", CID 48) created.

2024-01-30 01:18:23,876 DEBG 'CallHomeManager' stderr output: [INF] EV ORIGIN: "ietf-netconf-server" "done" ID 2 priority 0 for 23 subscribers published. [INF] EV ORIGIN: "ietf-netconf-server" "done" ID 2 priority 0 succeeded. [INF] EV ORIGIN: "ietf-netconf-notifications" "notif" ID 2 priority 0 for 4 subscribers published.

2024-01-30 01:18:23,876 DEBG 'orusw' stdout output: 2024-01-30 01:18:23/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "ietf-netconf-notifications" "notif" ID 2 processing. 2024-01-30 01:18:23/INF:UE_APP-OAM/sysrepo[RU01]: UE_APP-OAM/sysrepo, EV LISTEN: "ietf-netconf-notifications" "notif" ID 2 priority 0 success (remaining 0 subscribers).

2024-01-30 01:18:23,876 DEBG 'netopeer2-server' stdout output: [INF]: SR: EV LISTEN: "ietf-netconf-server" "done" ID 2 priority 0 processing (remaining 23 subscribers). [ERR]: LN: Unable to use Keyboard-Interactive authentication method without setting the name of the PAM configuration file first. [INF]: SR: EV LISTEN: "ietf-netconf-server" "done" ID 2 priority 0 success (remaining 0 subscribers). [INF]: LN: Call Home client "NC-Client-1" endpoint "default-ssh" connecting... [INF]: LN: Trying to connect via IPv4 to 192.168.216.2:4334. [INF]: LN: Successfully connected to 192.168.216.2:4334 over IPv4. [INF]: SR: Session 81 (user "root", CID 39) created.

2024-01-30 01:18:23,886 DEBG 'CallHomeManager' stderr output: 2024-01-30 01:18:23.886 INFO/UE_APP-OAM/CallHomeManager: CallHomeManager.py:173 - handleLinkManagerData() - [MPlaneLink=1] Replying to validation dn=Validation/1/LINK/SRV with data = {'status': 'OK', 'message': 'No error'}

2024-01-30 01:18:23,889 DEBG 'LinkManagerOru' stderr output:

michalvasko commented 8 months ago

Use the latest release, the notifications work differently so it makes no sense of fixing any problem in the older version. Also, please attach such long logs as attachments next time.

Krisscut commented 8 months ago

Ok I will create another ticket or get back to this one if reproduced on latest version. Thanks