Hi Team,
In our application, we are trying to upgrade ConfD from 6.6.1 to
8.0.4.
While starting ConfD from phase1 to phase2, we are facing the below exception,
Maapi maapi = null;
try (Socket cdbSocket = new Socket("127.0.0.1", 4565)) {
maapi = new Maapi(cdbSocket);
maapi.startPhase(2, true); //Exception is thrown here
} catch (ConfException e) {
log.error("Exception while starting ConfD Phase " + phase, e);
} catch (IOException e) {
log.error("Unable to start ConfD Phase " + phase, e);
}
> 2023-05-19 06:51:45.708 ERROR Exception while starting ConfD Phase 2
> **com.tailf.conf.ConfException: unexpected end of file**
> at com.tailf.conf.ConfInternal.readFill(ConfInternal.java:422)
> at com.tailf.conf.ConfInternal.termRead(ConfInternal.java:192)
> at com.tailf.conf.ConfInternal.requestTerm(ConfInternal.java:548)
> at com.tailf.conf.ConfInternal.requestTerm(ConfInternal.java:677)
> at com.tailf.maapi.Maapi.startPhase(Maapi.java:10409)
> at
> com.cisco.esc.yang.util.ConfigDataUtil.startConfDPhase(ConfigDataUtil.java:201)
> at com.cisco.esc.ESCStatus.run(ESCStatus.java:148)
> Caused by: java.io.EOFException: null
Could you please help us on this?
We do see the below issue in the confd log,
<INFO> 19-May-2023::07:31:08.997 dnd-st-esc-5-11-0-26-2 confd[55788]: - Starting to listen for Internal IPC on 127.0.0.1:4565
<INFO> 19-May-2023::07:31:09.443 dnd-st-esc-5-11-0-26-2 confd[55788]: - ConfD phase0 started
<NOTICE> 19-May-2023::07:31:33.512 dnd-st-esc-5-11-0-26-2 confd[55788]: - The notification replay store log 'escEvent' has been repaired
<NOTICE> 19-May-2023::07:31:33.520 dnd-st-esc-5-11-0-26-2 confd[55788]: - The notification replay store log 'lwmonEvent' has been repaired
<INFO> 19-May-2023::07:31:33.597 dnd-st-esc-5-11-0-26-2 confd[55788]: - ConfD phase1 started
<INFO> 19-May-2023::07:31:43.002 dnd-st-esc-5-11-0-26-2 confd[55788]: - Starting to listen for NETCONF SSH on 0.0.0.0:830
<CRIT> 19-May-2023::07:31:43.056 dnd-st-esc-5-11-0-26-2 confd[55788]: - Cannot bind to NETCONF socket 0.0.0.0:830 : permission denied (eacces)
Content of developer log,
<INFO> 19-May-2023::07:10:23.421 dnd-st-esc-5-11-0-26-2 confd[<0.69.0>]: confd embedded apps in early_phase0:
[]
<INFO> 19-May-2023::07:10:23.768 dnd-st-esc-5-11-0-26-2 confd[<0.99.0>]: confd mmap_schema handle_info got msg: timeout
<INFO> 19-May-2023::07:10:23.789 dnd-st-esc-5-11-0-26-2 confd[<0.9.0>]: confd embedded apps in phase0:
[]
<INFO> 19-May-2023::07:10:23.935 dnd-st-esc-5-11-0-26-2 confd[<0.137.0>]: devel-cdb Loaded schema file: ../esc_database/confd-cdb/C.cdb (v5 from ConfD version 8.0.4)
<INFO> 19-May-2023::07:10:23.979 dnd-st-esc-5-11-0-26-2 confd[<0.136.0>]: devel-cdb Loaded oper data from ../esc_database/confd-cdb/O.cdb (4 bytes data in 0.000s)
<INFO> 19-May-2023::07:10:23.980 dnd-st-esc-5-11-0-26-2 confd[<0.137.0>]: devel-cdb Loaded configuration from ../esc_database/confd-cdb/A.cdb (2.16 KiB data, 1 transaction in 0.000s)
<DEBUG> 19-May-2023::07:10:23.986 dnd-st-esc-5-11-0-26-2 confd[<0.136.0>]: devel-cdb cdb_op_lock_manager:grab_lock:178 mode=write id=#Ref<0.2041844420.3969384449.215258>
<INFO> 19-May-2023::07:10:23.986 dnd-st-esc-5-11-0-26-2 confd[<0.137.0>]: devel-cdb Compacted CDB journal file: 2 ms (149 nodes in memory, disk size 2.16 KiB -> 2.16 KiB)
<DEBUG> 19-May-2023::07:10:23.986 dnd-st-esc-5-11-0-26-2 confd[<0.136.0>]: devel-cdb cdb_op_lock_manager:log_lockpaths:943 path: []
<DEBUG> 19-May-2023::07:10:23.987 dnd-st-esc-5-11-0-26-2 confd[<0.131.0>]: devel-cdb cdb_op_lock_manager:reply:915 id=#Ref<0.2041844420.3969384449.215258> reply ok
<DEBUG> 19-May-2023::07:10:23.987 dnd-st-esc-5-11-0-26-2 confd[<0.136.0>]: devel-cdb cdb_op_lock_manager:release_lock:197 id=#Ref<0.2041844420.3969384449.215258>
<INFO> 19-May-2023::07:10:24.060 dnd-st-esc-5-11-0-26-2 confd[<0.9.0>]: confd Erlang emulator PID: 48762
<DEBUG> 19-May-2023::07:10:27.963 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from /opt/cisco/esc/confd/bin/confd_get_phase
<DEBUG> 19-May-2023::07:10:27.963 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb client /opt/cisco/esc/confd/bin/confd_get_phase has pid 48817/3
<DEBUG> 19-May-2023::07:10:27.964 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb inactive client /opt/cisco/esc/confd/bin/confd_get_phase closed
<DEBUG> 19-May-2023::07:10:27.974 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from /opt/cisco/esc/confd/bin/confd_get_phase
<DEBUG> 19-May-2023::07:10:27.974 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb client /opt/cisco/esc/confd/bin/confd_get_phase has pid 48818/3
<DEBUG> 19-May-2023::07:10:27.975 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb inactive client /opt/cisco/esc/confd/bin/confd_get_phase closed
<DEBUG> 19-May-2023::07:10:37.945 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from /opt/cisco/esc/confd/bin/confd_get_phase
<DEBUG> 19-May-2023::07:10:37.945 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb client /opt/cisco/esc/confd/bin/confd_get_phase has pid 48874/3
<DEBUG> 19-May-2023::07:10:37.946 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb inactive client /opt/cisco/esc/confd/bin/confd_get_phase closed
<DEBUG> 19-May-2023::07:11:05.747 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from Check_CDB_Phase
<DEBUG> 19-May-2023::07:11:05.750 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb inactive client Check_CDB_Phase closed
<INFO> 19-May-2023::07:11:06.602 dnd-st-esc-5-11-0-26-2 confd[<0.68.0>]: confd encryptedStrings keys provided from config
<INFO> 19-May-2023::07:11:06.603 dnd-st-esc-5-11-0-26-2 confd[<0.169.0>]: confd embedded apps in phase1:
[]
<INFO> 19-May-2023::07:11:06.631 dnd-st-esc-5-11-0-26-2 confd[<0.172.0>]: confd initializing kickers
<INFO> 19-May-2023::07:11:06.648 dnd-st-esc-5-11-0-26-2 confd[<0.172.0>]: confd initializing kickers: ok (0.017 s)
<DEBUG> 19-May-2023::07:11:06.736 dnd-st-esc-5-11-0-26-2 confd[<0.205.0>]: devel-cdb Loaded AAA data from CDB
<INFO> 19-May-2023::07:11:06.743 dnd-st-esc-5-11-0-26-2 confd[<0.238.0>]: confd delete "../esc_database/confd-cdb/rollback0.confirm" resulted in enoent
<INFO> 19-May-2023::07:11:06.743 dnd-st-esc-5-11-0-26-2 confd[<0.238.0>]: confd delete "../esc_database/confd-cdb/rollback0.confirm.bup" resulted in enoent
<INFO> 19-May-2023::07:11:06.766 dnd-st-esc-5-11-0-26-2 confd[<0.169.0>]: confd embedded apps in phase1_delayed:
[]
<DEBUG> 19-May-2023::07:11:13.004 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from esc_cdb_sub_daemon
<DEBUG> 19-May-2023::07:11:13.004 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from esc_cdb_sub_daemon
<DEBUG> 19-May-2023::07:11:13.022 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb new subscriber session for esc_cdb_sub_daemon
<DEBUG> 19-May-2023::07:11:13.022 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb new subscriber session for esc_cdb_sub_daemon
<DEBUG> 19-May-2023::07:11:13.031 dnd-st-esc-5-11-0-26-2 confd[<0.124.0>]: devel-c New daemon connected (name: esc_opdata_daemon_ca72a33c-ea66-4202-b060-753fcd14ed58, daemon id: 0)
<DEBUG> 19-May-2023::07:11:13.032 dnd-st-esc-5-11-0-26-2 confd[<0.124.0>]: devel-c New daemon connected (name: escnotifsender1684480273027, daemon id: 1)
<DEBUG> 19-May-2023::07:11:13.032 dnd-st-esc-5-11-0-26-2 confd[<0.124.0>]: devel-c New daemon connected (name: esc_action_daemon_cdbfa031-a412-41ae-a423-d9b7803aaf89, daemon id: 2)
<DEBUG> 19-May-2023::07:11:13.038 dnd-st-esc-5-11-0-26-2 confd[<0.124.0>]: devel-c New worker connected (daemon id: 1 worker id: 2)
<DEBUG> 19-May-2023::07:11:16.018 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb connect from Check_CDB_Phase
<DEBUG> 19-May-2023::07:11:16.019 dnd-st-esc-5-11-0-26-2 confd[<0.133.0>]: devel-cdb inactive client Check_CDB_Phase closed
<INFO> 19-May-2023::07:11:16.021 dnd-st-esc-5-11-0-26-2 confd[<0.263.0>]: confd embedded apps in phase2:
[]
We are checking on the permission issue. Meanwhile, is the above end of file
issue related to this permission issue.
Regards,
Srini