[NETCONF-324] Carbon clustered topology fails in -all- jobs Created: 28/Nov/16  Updated: 15/Mar/19  Resolved: 08/Dec/16

Status: Resolved
Project: netconf
Component/s: netconf
Affects Version/s: None
Fix Version/s: None

Type: Bug
Reporter: Vratko Polak Assignee: Unassigned
Resolution: Done Votes: 0
Labels: None
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

Operating System: All
Platform: All


Issue Links:
Duplicate
is duplicated by NETCONF-326 NPE when accessing a mount point from... Resolved
External issue ID: 7255

 Description   

Cluster netconf jobs are configuring a connection towards a testtool device.
While only jobs are passing [0] when checking whether device has been connected to; in recent Carbon snapshot all jobs, the same check fails [1].
"Mount point already exists" is printed to karaf.log but a previous test case [2] has not seen the device connected.

Here is the segment of karaf.log related to the failing test case:

2016-11-26 02:01:30,891 | INFO | h for user karaf | command | 46 - org.apache.karaf.log.command - 3.0.8 | ROBOT MESSAGE: Starting test Wait_For_Device_To_Become_Visible_For_Configurer
2016-11-26 02:01:31,633 | INFO | sing-executor-12 | NetconfDevice | 345 - org.opendaylight.netconf.sal-netconf-connector - 1.5.0.SNAPSHOT | RemoteDevice

{netconf-test-device}: Netconf connector initialized successfully
2016-11-26 02:01:31,856 | INFO | ult-dispatcher-6 | NetconfTopologyContext | 526 - org.opendaylight.netconf.topology-singleton - 1.2.0.SNAPSHOT | Master was selected: IpAddress [_ipv4Address=Ipv4Address [_value=10.29.13.13]]
2016-11-26 02:01:31,858 | INFO | ult-dispatcher-6 | RemoteDeviceConnectorImpl | 526 - org.opendaylight.netconf.topology-singleton - 1.2.0.SNAPSHOT | RemoteDevice{netconf-test-device}

: Concurrent rpc limit is smaller than 1, no limit will be enforced.
2016-11-26 02:01:31,862 | INFO | 5]-nio2-thread-2 | ClientSessionImpl | 30 - org.apache.sshd.core - 0.14.0 | Client session created
2016-11-26 02:01:31,864 | INFO | 5]-nio2-thread-2 | ClientSessionImpl | 30 - org.apache.sshd.core - 0.14.0 | Start flagging packets as pending until key exchange is done
2016-11-26 02:01:31,864 | INFO | 5]-nio2-thread-2 | ClientSessionImpl | 30 - org.apache.sshd.core - 0.14.0 | Server version string: SSH-2.0-SSHD-CORE-0.14.0
2016-11-26 02:01:31,914 | WARN | 5]-nio2-thread-8 | AcceptAllServerKeyVerifier | 30 - org.apache.sshd.core - 0.14.0 | Server at /10.29.13.13:17830 presented unverified DSA key: ec:32:46:a5:df:86:59:84:d9:21:14:b2:55:a1:21:f3
2016-11-26 02:01:31,916 | INFO | 5]-nio2-thread-1 | ClientSessionImpl | 30 - org.apache.sshd.core - 0.14.0 | Dequeing pending packets
2016-11-26 02:01:31,917 | INFO | 5]-nio2-thread-3 | ClientUserAuthServiceNew | 30 - org.apache.sshd.core - 0.14.0 | Received SSH_MSG_USERAUTH_FAILURE
2016-11-26 02:01:31,918 | INFO | 5]-nio2-thread-5 | UserAuthKeyboardInteractive | 30 - org.apache.sshd.core - 0.14.0 | Received Password authentication en-US
2016-11-26 02:01:31,919 | INFO | 5]-nio2-thread-7 | ClientUserAuthServiceNew | 30 - org.apache.sshd.core - 0.14.0 | Received SSH_MSG_USERAUTH_SUCCESS
2016-11-26 02:01:32,143 | INFO | ssing-executor-6 | MasterSalFacade | 526 - org.opendaylight.netconf.topology-singleton - 1.2.0.SNAPSHOT | RemoteDevice

{netconf-test-device}: Creating master data broker for device
2016-11-26 02:01:32,143 | ERROR | ssing-executor-6 | NetconfDevice | 345 - org.opendaylight.netconf.sal-netconf-connector - 1.5.0.SNAPSHOT | RemoteDevice{netconf-test-device}

: Initialization in sal failed, disconnecting from device
java.lang.IllegalStateException: Mount point already exists
at com.google.common.base.Preconditions.checkState(Preconditions.java:173)[65:com.google.guava:18.0.0]
at org.opendaylight.controller.md.sal.dom.broker.impl.mount.DOMMountPointServiceImpl.createMountPoint(DOMMountPointServiceImpl.java:41)[167:org.opendaylight.controller.sal-broker-impl:1.5.0.SNAPSHOT]
at Proxyb755215f_d895_4b8e_a8f1_449a5deeba4f.createMountPoint(Unknown Source)[:]
at org.opendaylight.controller.sal.dom.broker.osgi.DOMMountPointServiceProxy.createMountPoint(DOMMountPointServiceProxy.java:33)[167:org.opendaylight.controller.sal-broker-impl:1.5.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.sal.NetconfDeviceSalProvider$MountInstance.onTopologyDeviceConnected(NetconfDeviceSalProvider.java:140)[345:org.opendaylight.netconf.sal-netconf-connector:1.5.0.SNAPSHOT]
at org.opendaylight.netconf.topology.singleton.impl.MasterSalFacade.registerMasterMountPoint(MasterSalFacade.java:141)[526:org.opendaylight.netconf.topology-singleton:1.2.0.SNAPSHOT]
at org.opendaylight.netconf.topology.singleton.impl.MasterSalFacade.onDeviceConnected(MasterSalFacade.java:87)[526:org.opendaylight.netconf.topology-singleton:1.2.0.SNAPSHOT]
at org.opendaylight.netconf.topology.singleton.impl.MasterSalFacade.onDeviceConnected(MasterSalFacade.java:43)[526:org.opendaylight.netconf.topology-singleton:1.2.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice.handleSalInitializationSuccess(NetconfDevice.java:217)[345:org.opendaylight.netconf.sal-netconf-connector:1.5.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$SchemaSetup.setUpSchema(NetconfDevice.java:465)[345:org.opendaylight.netconf.sal-netconf-connector:1.5.0.SNAPSHOT]
at org.opendaylight.netconf.sal.connect.netconf.NetconfDevice$SchemaSetup.run(NetconfDevice.java:430)[345:org.opendaylight.netconf.sal-netconf-connector:1.5.0.SNAPSHOT]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_111]
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_111]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_111]
at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_111]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_111]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_111]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_111]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_111]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_111]
2016-11-26 02:01:32,144 | WARN | ssing-executor-6 | NetconfDeviceCommunicator | 345 - org.opendaylight.netconf.sal-netconf-connector - 1.5.0.SNAPSHOT | RemoteDevice

{netconf-test-device}

: Session terminated Session closed
2016-11-26 02:01:41,776 | INFO | h for user karaf | command | 46 - org.apache.karaf.log.command - 3.0.8 | ROBOT MESSAGE: Starting test Wait_For_Device_To_Become_Visible_For_Checker

[0] https://logs.opendaylight.org/releng/jenkins092/netconf-csit-3node-clustering-only-carbon/279/archives/log.html.gz#s1-s2-t5-k2-k1-k2-k2
[1] https://logs.opendaylight.org/releng/jenkins092/netconf-csit-3node-clustering-all-carbon/106/archives/log.html.gz#s1-s2-t5-k2-k1-k1-k2
[2] https://logs.opendaylight.org/releng/jenkins092/netconf-csit-3node-clustering-all-carbon/106/archives/log.html.gz#s1-s2-t2-k2-k1-k1-k1-k4-k1



 Comments   
Comment by Peter Gubka [ 08/Dec/16 ]

fixed by https://git.opendaylight.org/gerrit/#/c/48972/ in distribution project

Generated at Wed Feb 07 20:14:43 UTC 2024 using Jira 8.20.10#820010-sha1:ace47f9899e9ee25d7157d59aa17ab06aee30d3d.