<div dir="ltr">Just had this failure again[1], relevant log[2].<div><br></div><div>I still feel there&#39;s an issue here:</div><div><div>{&quot;jsonrpc&quot;: &quot;2.0&quot;, &quot;id&quot;: &quot;aa241c36-d61d-4f9f-9baf-040b00638d00&quot;, &quot;result&quot;: [{&quot;username&quot;: &quot;Unknown&quot;, &quot;displayInfo&quot;: [{&quot;tlsPort&quot;: &quot;-1&quot;, &quot;ipAddress&quot;: &quot;192.168.201.4&quot;, &quot;type&quot;: &quot;vnc&quot;, &quot;port&quot;: &quot;5900&quot;}, {&quot;tlsPort&quot;: &quot;5901&quot;, &quot;ipAddress&quot;: &quot;192.168.201.4&quot;, &quot;type&quot;: &quot;spice&quot;, &quot;port&quot;: &quot;-1&quot;}], &quot;hash&quot;: &quot;964568653906073065&quot;, &quot;acpiEnable&quot;: &quot;true&quot;, &quot;displayIp&quot;: &quot;192.168.201.4&quot;, &quot;guestFQDN&quot;: &quot;&quot;, &quot;vmId&quot;: &quot;c9db9f94-3086-47e4-b997-01564ecb5319&quot;, &quot;pid&quot;: &quot;23127&quot;, &quot;timeOffset&quot;: &quot;0&quot;, &quot;session&quot;: &quot;Unknown&quot;, &quot;displaySecurePort&quot;: &quot;-1&quot;, &quot;displayPort&quot;: &quot;5900&quot;, &quot;memUsage&quot;: &quot;0&quot;, &quot;guestIPs&quot;: &quot;&quot;, &quot;vcpuQuota&quot;: &quot;-1&quot;, &quot;kvmEnable&quot;: &quot;true&quot;, &quot;displayType&quot;: &quot;vnc&quot;, &quot;cpuUser&quot;: &quot;0.00&quot;, &quot;vmJobs&quot;: {}, &quot;monitorResponse&quot;: &quot;0&quot;, &quot;elapsedTime&quot;: &quot;477&quot;, &quot;vmType&quot;: &quot;kvm&quot;, &quot;cpuSys&quot;: &quot;0.00&quot;, &quot;status&quot;: &quot;Up&quot;, &quot;guestCPUCount&quot;: -1, &quot;appsList&quot;: [], &quot;clientIp&quot;: &quot;&quot;, &quot;statusTime&quot;: &quot;4295847140&quot;, &quot;vmName&quot;: &quot;vm0&quot;, &quot;vcpuPeriod&quot;: 100000}]}</div><div>2017-03-16 10:36:17,558-04 DEBUG [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor] (SSL Stomp Reactor) [cb68faa] Internal server error 880 &gt; 879: java.lang.IllegalArgumentException: 880 &gt; 879</div><div><span class="gmail-Apple-tab-span" style="white-space:pre">        </span>at java.util.Arrays.copyOfRange(Arrays.java:3519) [rt.jar:1.8.0_121]</div><div><span class="gmail-Apple-tab-span" style="white-space:pre">        </span>at org.ovirt.vdsm.jsonrpc.client.reactors.stomp.StompCommonClient.processIncoming(StompCommonClient.java:145) [vdsm-jsonrpc-java-client.jar:]</div><div><span class="gmail-Apple-tab-span" style="white-space:pre">        </span>at org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process(ReactorClient.java:195) [vdsm-jsonrpc-java-client.jar:]</div><div><span class="gmail-Apple-tab-span" style="white-space:pre">        </span>at org.ovirt.vdsm.jsonrpc.client.reactors.SSLClient.process(SSLClient.java:121) [vdsm-jsonrpc-java-client.jar:]</div><div><span class="gmail-Apple-tab-span" style="white-space:pre">        </span>at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.lambda$processChannels$2(Reactor.java:80) [vdsm-jsonrpc-java-client.jar:]</div></div><div><br></div><div>Y.</div><div><br></div><div>[1] <a href="http://jenkins.ovirt.org/job/ovirt-system-tests_master_check-patch-el7-x86_64/327/">http://jenkins.ovirt.org/job/ovirt-system-tests_master_check-patch-el7-x86_64/327/</a></div><div>[2] <a href="http://jenkins.ovirt.org/job/ovirt-system-tests_master_check-patch-el7-x86_64/327/artifact/exported-artifacts/basic_suite_4.1__logs/test_logs/basic-suite-4.1/post-004_basic_sanity.py/lago-basic-suite-4-1-engine/_var_log/ovirt-engine/engine.log">http://jenkins.ovirt.org/job/ovirt-system-tests_master_check-patch-el7-x86_64/327/artifact/exported-artifacts/basic_suite_4.1__logs/test_logs/basic-suite-4.1/post-004_basic_sanity.py/lago-basic-suite-4-1-engine/_var_log/ovirt-engine/engine.log</a></div></div><div class="gmail_extra"><br><div class="gmail_quote">On Fri, Mar 3, 2017 at 9:53 AM, Pavel Zhukov <span dir="ltr">&lt;<a href="mailto:pzhukov@redhat.com" target="_blank">pzhukov@redhat.com</a>&gt;</span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><br>
Hi,<br>
<br>
This is one-time failure. Non reproducible. disk_id was not inserted<br>
into DB because of jsonrpc/networking issue. But once OST runs on<br>
localhost networking (should not) be a problem here so adding Piotr.<br>
<br>
Failed jobs:<br>
<a href="http://jenkins.ovirt.org/view/experimental%20jobs/job/test-repo_ovirt_experimental_4.1/845/" rel="noreferrer" target="_blank">http://jenkins.ovirt.org/view/<wbr>experimental%20jobs/job/test-<wbr>repo_ovirt_experimental_4.1/<wbr>845/</a><br>
<br>
Logs:<br>
<a href="http://jenkins.ovirt.org/view/experimental%20jobs/job/test-repo_ovirt_experimental_4.1/845/artifact/exported-artifacts" rel="noreferrer" target="_blank">http://jenkins.ovirt.org/view/<wbr>experimental%20jobs/job/test-<wbr>repo_ovirt_experimental_4.1/<wbr>845/artifact/exported-<wbr>artifacts</a><br>
<a href="http://jenkins.ovirt.org/view/experimental%20jobs/job/test-repo_ovirt_experimental_4.1/845/artifact/*zip*/archive.zip" rel="noreferrer" target="_blank">http://jenkins.ovirt.org/view/<wbr>experimental%20jobs/job/test-<wbr>repo_ovirt_experimental_4.1/<wbr>845/artifact/*zip*/archive.zip</a><br>
<br>
[LOGS SNIPPETS]<br>
<br>
  35897:2017-03-02 07:12:28,721-05 INFO  [org.ovirt.engine.core.<wbr>vdsbroker.irsbroker.<wbr>CreateImageVDSCommand] (default task-19) [4adab878] START, CreateImageVDSCommand( CreateImageVDSCommandParameter<wbr>s:{runAsync=&#39;true&#39;, storagePoolId=&#39;9314c679-9a20-<wbr>48b4-b62a-f7b6a3703287&#39;, ignoreFailoverLimit=&#39;false&#39;, storageDomainId=&#39;49226db8-<wbr>e43f-4a15-a3a3-0bddd690d06b&#39;, imageGroupId=&#39;9cf5a90d-05fc-<wbr>45de-a3c1-adbb6822150e&#39;, imageSizeInBytes=&#39;2&#39;, volumeFormat=&#39;COW&#39;, newImageId=&#39;c5275e2f-ac80-<wbr>47c2-a43e-b5a0420a4211&#39;, newImageDescription=&#39;{&quot;<wbr>DiskAlias&quot;:&quot;vm0_disk0&quot;,&quot;<wbr>DiskDescription&quot;:&quot;&quot;}&#39;, imageInitialSizeInBytes=&#39;0&#39;}), log id: 657ef611<br>
  35920:{&quot;jsonrpc&quot;:&quot;2.0&quot;,&quot;<wbr>method&quot;:&quot;Volume.create&quot;,&quot;<wbr>params&quot;:{&quot;volumeID&quot;:&quot;c5275e2f-<wbr>ac80-47c2-a43e-b5a0420a4211&quot;,&quot;<wbr>storagepoolID&quot;:&quot;9314c679-9a20-<wbr>48b4-b62a-f7b6a3703287&quot;,&quot;<wbr>storagedomainID&quot;:&quot;49226db8-<wbr>e43f-4a15-a3a3-0bddd690d06b&quot;,&quot;<wbr>imageID&quot;:&quot;9cf5a90d-05fc-45de-<wbr>a3c1-adbb6822150e&quot;,&quot;size&quot;:&quot;2&quot;,<wbr>&quot;volFormat&quot;:4,&quot;preallocate&quot;:2,<wbr>&quot;diskType&quot;:2,&quot;desc&quot;:&quot;{\&quot;<wbr>DiskAlias\&quot;:\&quot;vm0_disk0\&quot;,\&quot;<wbr>DiskDescription\&quot;:\&quot;\&quot;}&quot;,&quot;<wbr>srcImgUUID&quot;:&quot;00000000-0000-<wbr>0000-0000-000000000000&quot;,&quot;<wbr>srcVolUUID&quot;:&quot;00000000-0000-<wbr>0000-0000-000000000000&quot;},&quot;id&quot;:<wbr>&quot;0a757eae-0cc2-4f15-9bb5-<wbr>05ad3221f697&quot;}   &lt;=== NOTE NULL character here<br>
  35926:&lt;JsonRpcRequest id: &quot;0a757eae-0cc2-4f15-9bb5-<wbr>05ad3221f697&quot;, method: Volume.create, params: {volumeID=c5275e2f-ac80-47c2-<wbr>a43e-b5a0420a4211, storagepoolID=9314c679-9a20-<wbr>48b4-b62a-f7b6a3703287, storagedomainID=49226db8-e43f-<wbr>4a15-a3a3-0bddd690d06b, imageID=9cf5a90d-05fc-45de-<wbr>a3c1-adbb6822150e, size=2, volFormat=4, preallocate=2, diskType=2, desc={&quot;DiskAlias&quot;:&quot;vm0_disk0&quot;,<wbr>&quot;DiskDescription&quot;:&quot;&quot;}, srcImgUUID=00000000-0000-0000-<wbr>0000-000000000000, srcVolUUID=00000000-0000-0000-<wbr>0000-000000000000}&gt;<br>
  35996:2017-03-02 07:12:30,468-05 ERROR [org.ovirt.engine.core.<wbr>vdsbroker.irsbroker.<wbr>IrsBrokerCommand] (default task-19) [4adab878] ERROR, CreateImageVDSCommand( CreateImageVDSCommandParameter<wbr>s:{runAsync=&#39;true&#39;, storagePoolId=&#39;9314c679-9a20-<wbr>48b4-b62a-f7b6a3703287&#39;, ignoreFailoverLimit=&#39;false&#39;, storageDomainId=&#39;49226db8-<wbr>e43f-4a15-a3a3-0bddd690d06b&#39;, imageGroupId=&#39;9cf5a90d-05fc-<wbr>45de-a3c1-adbb6822150e&#39;, imageSizeInBytes=&#39;2&#39;, volumeFormat=&#39;COW&#39;, newImageId=&#39;c5275e2f-ac80-<wbr>47c2-a43e-b5a0420a4211&#39;, newImageDescription=&#39;{&quot;<wbr>DiskAlias&quot;:&quot;vm0_disk0&quot;,&quot;<wbr>DiskDescription&quot;:&quot;&quot;}&#39;, imageInitialSizeInBytes=&#39;0&#39;}), exception: VDSGenericException: VDSNetworkException: 880 &gt; 879, log id: 657ef611<br>
  36511:2017-03-02 07:12:35,744-05 INFO  [org.ovirt.engine.core.bll.<wbr>storage.disk.image.<wbr>AddImageFromScratchCommand] (default task-19) [4adab878] Command [id=35007fba-781c-4a15-8f29-<wbr>a8efe441b2f3]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.<wbr>businessentities.storage.<wbr>DiskImage; snapshot: 9cf5a90d-05fc-45de-a3c1-<wbr>adbb6822150e.<br>
2017-03-02 07:12:35,766-05 WARN  [org.ovirt.engine.core.dal.<wbr>dbbroker.auditloghandling.<wbr>AuditLogDirector] (DefaultQuartzScheduler7) [6dc0e793] EVENT_ID: SYSTEM_CHANGE_STORAGE_POOL_<wbr>STATUS_PROBLEMATIC(980), Correlation ID: 6dc0e793, Call Stack: null, Custom Event ID: -1, Message: Invalid status on Data Center test-dc. Setting status to Non Responsive.<br>
2017-03-02 07:12:35,773-05 INFO  [org.ovirt.engine.core.bll.<wbr>tasks.AsyncTaskManager] (default task-19) [4adab878] Removed task &#39;10b3bd58-8f3e-4b23-a8b6-<wbr>27db110810d5&#39; from DataBase<br>
2017-03-02 07:12:35,779-05 DEBUG [org.ovirt.engine.core.dal.<wbr>dbbroker.<wbr>CustomSQLErrorCodeSQLException<wbr>Translator] (default task-19) [4adab878] Translating SQLException with SQL state &#39;23503&#39;, error code &#39;0&#39;, message [ERROR: insert or update on table &quot;disk_vm_element&quot; violates foreign key constraint &quot;fk_disk_vm_element_base_<wbr>disks&quot;<br>
  Detail: Key (disk_id)=(9cf5a90d-05fc-45de-<wbr>a3c1-adbb6822150e) is not present in table &quot;base_disks&quot;.<br>
<br>
--<br>
Pavel<br>
______________________________<wbr>_________________<br>
Devel mailing list<br>
<a href="mailto:Devel@ovirt.org">Devel@ovirt.org</a><br>
<a href="http://lists.ovirt.org/mailman/listinfo/devel" rel="noreferrer" target="_blank">http://lists.ovirt.org/<wbr>mailman/listinfo/devel</a><br>
</blockquote></div><br></div>