[ovirt-devel] [ OST Failure Report ] [ ovirt-engine-4.1 ] [ 02.03.2017 ] [004_basic_sanity]

Thu Mar 16 16:33:05 UTC 2017

+ infra

On Thu, Mar 16, 2017 at 5:32 PM, Piotr Kliczewski
<piotr.kliczewski at gmail.com> wrote:
> I checked the logs and I checked that the job somehow used older
> version without the fix:
>
> 2017-03-16 14:16:07,212::INFO::repoman.common.stores.RPM::Adding
> package /var/lib/lago/reposync/ovirt-4.1-el7/noarch/vdsm-jsonrpc-java-1.3.8-1.el7.centos.noarch.rpm
> to repo /dev/shm/ost/deployment-basic-suite-4.1/default/internal_repo
>
> How is it possible?
>
> On Thu, Mar 16, 2017 at 4:30 PM, Yaniv Kaul <ykaul at redhat.com> wrote:
>> Just had this failure again[1], relevant log[2].
>>
>> I still feel there's an issue here:
>> {"jsonrpc": "2.0", "id": "aa241c36-d61d-4f9f-9baf-040b00638d00", "result":
>> [{"username": "Unknown", "displayInfo": [{"tlsPort": "-1", "ipAddress":
>> "192.168.201.4", "type": "vnc", "port": "5900"}, {"tlsPort": "5901",
>> "ipAddress": "192.168.201.4", "type": "spice", "port": "-1"}], "hash":
>> "964568653906073065", "acpiEnable": "true", "displayIp": "192.168.201.4",
>> "guestFQDN": "", "vmId": "c9db9f94-3086-47e4-b997-01564ecb5319", "pid":
>> "23127", "timeOffset": "0", "session": "Unknown", "displaySecurePort": "-1",
>> "displayPort": "5900", "memUsage": "0", "guestIPs": "", "vcpuQuota": "-1",
>> "kvmEnable": "true", "displayType": "vnc", "cpuUser": "0.00", "vmJobs": {},
>> "monitorResponse": "0", "elapsedTime": "477", "vmType": "kvm", "cpuSys":
>> "0.00", "status": "Up", "guestCPUCount": -1, "appsList": [], "clientIp": "",
>> "statusTime": "4295847140", "vmName": "vm0", "vcpuPeriod": 100000}]}
>> 2017-03-16 10:36:17,558-04 DEBUG
>> [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor] (SSL Stomp Reactor)
>> [cb68faa] Internal server error 880 > 879:
>> java.lang.IllegalArgumentException: 880 > 879
>> at java.util.Arrays.copyOfRange(Arrays.java:3519) [rt.jar:1.8.0_121]
>> at
>> org.ovirt.vdsm.jsonrpc.client.reactors.stomp.StompCommonClient.processIncoming(StompCommonClient.java:145)
>> [vdsm-jsonrpc-java-client.jar:]
>> at
>> org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process(ReactorClient.java:195)
>> [vdsm-jsonrpc-java-client.jar:]
>> at
>> org.ovirt.vdsm.jsonrpc.client.reactors.SSLClient.process(SSLClient.java:121)
>> [vdsm-jsonrpc-java-client.jar:]
>> at
>> org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.lambda$processChannels$2(Reactor.java:80)
>> [vdsm-jsonrpc-java-client.jar:]
>>
>> Y.
>>
>> [1]
>> http://jenkins.ovirt.org/job/ovirt-system-tests_master_check-patch-el7-x86_64/327/
>> [2]
>> 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
>>
>> On Fri, Mar 3, 2017 at 9:53 AM, Pavel Zhukov <pzhukov at redhat.com> wrote:
>>>
>>>
>>> Hi,
>>>
>>> This is one-time failure. Non reproducible. disk_id was not inserted
>>> into DB because of jsonrpc/networking issue. But once OST runs on
>>> localhost networking (should not) be a problem here so adding Piotr.
>>>
>>> Failed jobs:
>>>
>>> http://jenkins.ovirt.org/view/experimental%20jobs/job/test-repo_ovirt_experimental_4.1/845/
>>>
>>> Logs:
>>>
>>> http://jenkins.ovirt.org/view/experimental%20jobs/job/test-repo_ovirt_experimental_4.1/845/artifact/exported-artifacts
>>>
>>> http://jenkins.ovirt.org/view/experimental%20jobs/job/test-repo_ovirt_experimental_4.1/845/artifact/*zip*/archive.zip
>>>
>>> [LOGS SNIPPETS]
>>>
>>>   35897:2017-03-02 07:12:28,721-05 INFO
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (default
>>> task-19) [4adab878] START, CreateImageVDSCommand(
>>> CreateImageVDSCommandParameters:{runAsync='true',
>>> storagePoolId='9314c679-9a20-48b4-b62a-f7b6a3703287',
>>> ignoreFailoverLimit='false',
>>> storageDomainId='49226db8-e43f-4a15-a3a3-0bddd690d06b',
>>> imageGroupId='9cf5a90d-05fc-45de-a3c1-adbb6822150e', imageSizeInBytes='2',
>>> volumeFormat='COW', newImageId='c5275e2f-ac80-47c2-a43e-b5a0420a4211',
>>> newImageDescription='{"DiskAlias":"vm0_disk0","DiskDescription":""}',
>>> imageInitialSizeInBytes='0'}), log id: 657ef611
>>>
>>> 35920:{"jsonrpc":"2.0","method":"Volume.create","params":{"volumeID":"c5275e2f-ac80-47c2-a43e-b5a0420a4211","storagepoolID":"9314c679-9a20-48b4-b62a-f7b6a3703287","storagedomainID":"49226db8-e43f-4a15-a3a3-0bddd690d06b","imageID":"9cf5a90d-05fc-45de-a3c1-adbb6822150e","size":"2","volFormat":4,"preallocate":2,"diskType":2,"desc":"{\"DiskAlias\":\"vm0_disk0\",\"DiskDescription\":\"\"}","srcImgUUID":"00000000-0000-0000-0000-000000000000","srcVolUUID":"00000000-0000-0000-0000-000000000000"},"id":"0a757eae-0cc2-4f15-9bb5-05ad3221f697"}
>>> <=== NOTE NULL character here
>>>   35926:<JsonRpcRequest id: "0a757eae-0cc2-4f15-9bb5-05ad3221f697",
>>> method: Volume.create, params:
>>> {volumeID=c5275e2f-ac80-47c2-a43e-b5a0420a4211,
>>> storagepoolID=9314c679-9a20-48b4-b62a-f7b6a3703287,
>>> storagedomainID=49226db8-e43f-4a15-a3a3-0bddd690d06b,
>>> imageID=9cf5a90d-05fc-45de-a3c1-adbb6822150e, size=2, volFormat=4,
>>> preallocate=2, diskType=2,
>>> desc={"DiskAlias":"vm0_disk0","DiskDescription":""},
>>> srcImgUUID=00000000-0000-0000-0000-000000000000,
>>> srcVolUUID=00000000-0000-0000-0000-000000000000}>
>>>   35996:2017-03-02 07:12:30,468-05 ERROR
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (default
>>> task-19) [4adab878] ERROR, CreateImageVDSCommand(
>>> CreateImageVDSCommandParameters:{runAsync='true',
>>> storagePoolId='9314c679-9a20-48b4-b62a-f7b6a3703287',
>>> ignoreFailoverLimit='false',
>>> storageDomainId='49226db8-e43f-4a15-a3a3-0bddd690d06b',
>>> imageGroupId='9cf5a90d-05fc-45de-a3c1-adbb6822150e', imageSizeInBytes='2',
>>> volumeFormat='COW', newImageId='c5275e2f-ac80-47c2-a43e-b5a0420a4211',
>>> newImageDescription='{"DiskAlias":"vm0_disk0","DiskDescription":""}',
>>> imageInitialSizeInBytes='0'}), exception: VDSGenericException:
>>> VDSNetworkException: 880 > 879, log id: 657ef611
>>>   36511:2017-03-02 07:12:35,744-05 INFO
>>> [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
>>> (default task-19) [4adab878] Command
>>> [id=35007fba-781c-4a15-8f29-a8efe441b2f3]: Compensating NEW_ENTITY_ID of
>>> org.ovirt.engine.core.common.businessentities.storage.DiskImage; snapshot:
>>> 9cf5a90d-05fc-45de-a3c1-adbb6822150e.
>>> 2017-03-02 07:12:35,766-05 WARN
>>> [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
>>> (DefaultQuartzScheduler7) [6dc0e793] EVENT_ID:
>>> SYSTEM_CHANGE_STORAGE_POOL_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.
>>> 2017-03-02 07:12:35,773-05 INFO
>>> [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (default task-19)
>>> [4adab878] Removed task '10b3bd58-8f3e-4b23-a8b6-27db110810d5' from DataBase
>>> 2017-03-02 07:12:35,779-05 DEBUG
>>> [org.ovirt.engine.core.dal.dbbroker.CustomSQLErrorCodeSQLExceptionTranslator]
>>> (default task-19) [4adab878] Translating SQLException with SQL state
>>> '23503', error code '0', message [ERROR: insert or update on table
>>> "disk_vm_element" violates foreign key constraint
>>> "fk_disk_vm_element_base_disks"
>>>   Detail: Key (disk_id)=(9cf5a90d-05fc-45de-a3c1-adbb6822150e) is not
>>> present in table "base_disks".
>>>
>>> --
>>> Pavel
>>> _______________________________________________
>>> Devel mailing list
>>> Devel at ovirt.org
>>> http://lists.ovirt.org/mailman/listinfo/devel
>>
>>
>>
>> _______________________________________________
>> Devel mailing list
>> Devel at ovirt.org
>> http://lists.ovirt.org/mailman/listinfo/devel