January 2017 - Devel - oVirt List Archives

[VDSM] WARNING: mailbox tests timeout
by Nir Soffer 29 Jan '17

29 Jan '17

Hi all, I have seen the new storage mailbox test failing with a timeout twice. If you see this in your builds, please complain here. The cause for the timeout seems to be: 09:56:02 2017-01-29 09:53:59,830 ERROR (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor: mailbox 7 checksum failed, not clearing mailbox, clearing newMail. (storage_mailbox:630) We see this error in vdsm logs sometimes, needs investigation. 09:56:02 ====================================================================== 09:56:02 FAIL: test_send_receive (storage_mailbox_test.TestMailbox) 09:56:02 ---------------------------------------------------------------------- 09:56:02 Traceback (most recent call last): 09:56:02 File "/home/jenkins/workspace/vdsm_master_check-patch-fc25-x86_64/vdsm/tests/storage_mailbox_test.py", line 132, in test_send_receive 09:56:02 self.assertFalse(expired, 'message was not processed on time') 09:56:02 AssertionError: message was not processed on time 09:56:02 -------------------- >> begin captured logging << -------------------- 09:56:02 2017-01-29 09:53:55,337 DEBUG (MainThread) [storage.ThreadPool] Enter - name: mailbox-hsm, numThreads: 5, waitTimeout: 0.15, maxTasks: 500 (threadPool:36) 09:56:02 2017-01-29 09:53:55,337 DEBUG (mailbox-hsm/0) [root] START thread <Thread(mailbox-hsm/0, started daemon 140445079619328)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc0960a0d0>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:55,338 DEBUG (mailbox-hsm/1) [root] START thread <Thread(mailbox-hsm/1, started daemon 140445631756032)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc096180d0>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:55,339 DEBUG (mailbox-hsm/2) [root] START thread <Thread(mailbox-hsm/2, started daemon 140444826597120)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc09618b10>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:55,339 DEBUG (mailbox-hsm/3) [root] START thread <Thread(mailbox-hsm/3, started daemon 140444784633600)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc09618c50>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:55,340 DEBUG (MainThread) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 /usr/bin/dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/outbox iflag=direct,fullblock bs=512 count=8 skip=56 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:55,340 DEBUG (mailbox-hsm/4) [root] START thread <Thread(mailbox-hsm/4, started daemon 140445071226624)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc09618590>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:55,646 DEBUG (MainThread) [storage.Misc.excCmd] SUCCESS: <err> = '8+0 records in\n8+0 records out\n4096 bytes (4.1 kB, 4.0 KiB) copied, 0.296994 s, 13.8 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:55,646 INFO (MainThread) [storage.MailBox.HsmMailMonitor] HSM_MailMonitor sending mail to SPM - ['/usr/bin/dd', 'of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox', 'iflag=fullblock', 'oflag=direct', 'conv=notrunc', 'bs=512', 'seek=56'] (storage_mailbox:394) 09:56:02 2017-01-29 09:53:55,647 DEBUG (MainThread) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 /usr/bin/dd of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=fullblock oflag=direct conv=notrunc bs=512 seek=56 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:58,798 DEBUG (MainThread) [storage.Misc.excCmd] SUCCESS: <err> = '8+0 records in\n8+0 records out\n4096 bytes (4.1 kB, 4.0 KiB) copied, 3.14004 s, 1.3 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:58,807 DEBUG (mailbox-hsm) [storage.MailBox.HsmMailMonitor] START thread <Thread(mailbox-hsm, started daemon 140445049673472)> (func=<bound method HSM_MailMonitor.run of <storage.storage_mailbox.HSM_MailMonitor object at 0x7fbc0960a9d0>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:58,808 DEBUG (MainThread) [storage.Mailbox.HSM] HSM_MailboxMonitor created for pool 5d928855-b09b-47a7-b920-bd2d2eb5808c (storage_mailbox:209) 09:56:02 2017-01-29 09:53:58,809 DEBUG (MainThread) [storage.ThreadPool] Enter - name: mailbox-spm, numThreads: 5, waitTimeout: 0.15, maxTasks: 500 (threadPool:36) 09:56:02 2017-01-29 09:53:58,818 DEBUG (mailbox-spm/0) [root] START thread <Thread(mailbox-spm/0, started daemon 140445041280768)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc09608890>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:58,820 DEBUG (mailbox-spm/1) [root] START thread <Thread(mailbox-spm/1, started daemon 140444818204416)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc09608490>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:58,820 DEBUG (mailbox-spm/2) [root] START thread <Thread(mailbox-spm/2, started daemon 140444809811712)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc09608110>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:58,821 DEBUG (mailbox-spm/3) [root] START thread <Thread(mailbox-spm/3, started daemon 140444801419008)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc0961bb90>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:58,822 DEBUG (mailbox-spm/4) [root] START thread <Thread(mailbox-spm/4, started daemon 140444793026304)> (func=<bound method WorkerThread.run of <vdsm.storage.threadPool.WorkerThread object at 0x7fbc0961bc10>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:58,822 DEBUG (MainThread) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor - clearing outgoing mail, command is: ['dd', 'of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/outbox', 'oflag=direct', 'iflag=fullblock', 'conv=notrunc', 'count=1'] (storage_mailbox:583) 09:56:02 2017-01-29 09:53:58,823 DEBUG (MainThread) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/outbox oflag=direct iflag=fullblock conv=notrunc count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:59,561 DEBUG (MainThread) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.721626 s, 56.8 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:59,562 DEBUG (mailbox-spm) [storage.MailBox.SpmMailMonitor] START thread <Thread(mailbox-spm, started daemon 140444776240896)> (func=<bound method SPM_MailMonitor.run of <storage.storage_mailbox.SPM_MailMonitor instance at 0x7fbc0c610b90>>, args=(), kwargs={}) (concurrent:183) 09:56:02 2017-01-29 09:53:59,563 DEBUG (MainThread) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor created for pool 5d928855-b09b-47a7-b920-bd2d2eb5808c (storage_mailbox:593) 09:56:02 2017-01-29 09:53:59,563 DEBUG (MainThread) [storage.SPM.Messages.Extend] new extend msg created: domain: 8adbc85e-e554-4ae0-b318-8a5465fe5fe1, volume: d772f1c6-3ebb-43c3-a42e-73fcd8255a5f (storage_mailbox:125) 09:56:02 2017-01-29 09:53:59,563 DEBUG (mailbox-hsm) [storage.MailBox.HsmMailMonitor] HSM_MailMonitor - start: 64, end: 128, len: 4096, message(1/63): '1xtnd\xe1_\xfeeT\x8a\x18\xb3\xe0JT\xe5^\xc8\xdb\x8a_Z%\xd8\xfcs.\xa4\xc3C\xbb>\xc6\xf1r\xd7000000000000006400000000000' (storage_mailbox:435) 09:56:02 2017-01-29 09:53:59,564 DEBUG (mailbox-hsm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 /usr/bin/dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/outbox iflag=direct,fullblock bs=512 count=8 skip=56 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:59,572 DEBUG (mailbox-spm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:59,589 DEBUG (mailbox-spm) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.0080494 s, 5.1 MB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:59,600 DEBUG (mailbox-hsm) [storage.Misc.excCmd] SUCCESS: <err> = '8+0 records in\n8+0 records out\n4096 bytes (4.1 kB, 4.0 KiB) copied, 0.023761 s, 172 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:59,601 INFO (mailbox-hsm) [storage.MailBox.HsmMailMonitor] HSM_MailMonitor sending mail to SPM - ['/usr/bin/dd', 'of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox', 'iflag=fullblock', 'oflag=direct', 'conv=notrunc', 'bs=512', 'seek=56'] (storage_mailbox:394) 09:56:02 2017-01-29 09:53:59,601 DEBUG (mailbox-hsm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 /usr/bin/dd of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=fullblock oflag=direct conv=notrunc bs=512 seek=56 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:59,691 DEBUG (mailbox-spm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:59,828 DEBUG (mailbox-spm) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.128307 s, 319 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:59,830 ERROR (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor: mailbox 7 checksum failed, not clearing mailbox, clearing newMail. (storage_mailbox:630) 09:56:02 2017-01-29 09:53:59,931 DEBUG (mailbox-spm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:53:59,976 DEBUG (mailbox-spm) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.0354083 s, 1.2 MB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:53:59,977 ERROR (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor: mailbox 7 checksum failed, not clearing mailbox, clearing newMail. (storage_mailbox:630) 09:56:02 2017-01-29 09:54:00,078 DEBUG (mailbox-spm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:54:00,220 DEBUG (mailbox-spm) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.133017 s, 308 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:54:00,222 ERROR (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor: mailbox 7 checksum failed, not clearing mailbox, clearing newMail. (storage_mailbox:630) 09:56:02 2017-01-29 09:54:00,323 DEBUG (mailbox-spm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:54:00,364 DEBUG (mailbox-spm) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.0325451 s, 1.3 MB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:54:00,366 ERROR (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor: mailbox 7 checksum failed, not clearing mailbox, clearing newMail. (storage_mailbox:630) 09:56:02 2017-01-29 09:54:00,466 DEBUG (mailbox-spm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 dd if=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=40960 (cwd None) (commands:69) 09:56:02 2017-01-29 09:54:00,610 DEBUG (mailbox-spm) [storage.Misc.excCmd] SUCCESS: <err> = '1+0 records in\n1+0 records out\n40960 bytes (41 kB, 40 KiB) copied, 0.129057 s, 317 kB/s\n'; <rc> = 0 (commands:93) 09:56:02 2017-01-29 09:54:00,612 ERROR (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor: mailbox 7 checksum failed, not clearing mailbox, clearing newMail. (storage_mailbox:630) 09:56:02 2017-01-29 09:54:00,778 DEBUG (mailbox-spm/1) [root] FINISH thread <Thread(mailbox-spm/1, started daemon 140444818204416)> (concurrent:186) 09:56:02 2017-01-29 09:54:00,778 DEBUG (mailbox-spm/2) [root] FINISH thread <Thread(mailbox-spm/2, started daemon 140444809811712)> (concurrent:186) 09:56:02 2017-01-29 09:54:00,777 DEBUG (mailbox-spm/0) [root] FINISH thread <Thread(mailbox-spm/0, started daemon 140445041280768)> (concurrent:186) 09:56:02 2017-01-29 09:54:00,783 DEBUG (mailbox-spm/4) [root] FINISH thread <Thread(mailbox-spm/4, started daemon 140444793026304)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,783 DEBUG (mailbox-spm/3) [root] FINISH thread <Thread(mailbox-spm/3, started daemon 140444801419008)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,785 INFO (mailbox-spm) [storage.MailBox.SpmMailMonitor] SPM_MailMonitor - Incoming mail monitoring thread stopped (storage_mailbox:805) 09:56:15 2017-01-29 09:54:00,785 DEBUG (mailbox-spm) [storage.MailBox.SpmMailMonitor] FINISH thread <Thread(mailbox-spm, started daemon 140444776240896)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,901 DEBUG (mailbox-hsm) [storage.Misc.excCmd] SUCCESS: <err> = '8+0 records in\n8+0 records out\n4096 bytes (4.1 kB, 4.0 KiB) copied, 1.28855 s, 3.2 kB/s\n'; <rc> = 0 (commands:93) 09:56:15 2017-01-29 09:54:00,901 INFO (mailbox-hsm) [storage.MailBox.HsmMailMonitor] HSM_MailboxMonitor - Incoming mail monitoring thread stopped, clearing outgoing mail (storage_mailbox:518) 09:56:15 2017-01-29 09:54:00,901 INFO (mailbox-hsm) [storage.MailBox.HsmMailMonitor] HSM_MailMonitor sending mail to SPM - ['/usr/bin/dd', 'of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox', 'iflag=fullblock', 'oflag=direct', 'conv=notrunc', 'bs=512', 'seek=56'] (storage_mailbox:394) 09:56:15 2017-01-29 09:54:00,902 DEBUG (mailbox-hsm) [storage.Misc.excCmd] /usr/bin/taskset --cpu-list 0-1 /usr/bin/dd of=/var/tmp/tmp71q4uJ/5d928855-b09b-47a7-b920-bd2d2eb5808c/mastersd/dom_md/inbox iflag=fullblock oflag=direct conv=notrunc bs=512 seek=56 (cwd None) (commands:69) 09:56:15 2017-01-29 09:54:00,912 DEBUG (mailbox-hsm/4) [root] FINISH thread <Thread(mailbox-hsm/4, started daemon 140445071226624)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,911 DEBUG (mailbox-hsm/2) [root] FINISH thread <Thread(mailbox-hsm/2, started daemon 140444826597120)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,910 DEBUG (mailbox-hsm/1) [root] FINISH thread <Thread(mailbox-hsm/1, started daemon 140445631756032)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,910 DEBUG (mailbox-hsm/3) [root] FINISH thread <Thread(mailbox-hsm/3, started daemon 140444784633600)> (concurrent:186) 09:56:15 2017-01-29 09:54:00,910 DEBUG (mailbox-hsm/0) [root] FINISH thread <Thread(mailbox-hsm/0, started daemon 140445079619328)> (concurrent:186) 09:56:15 2017-01-29 09:54:02,394 DEBUG (mailbox-hsm) [storage.Misc.excCmd] SUCCESS: <err> = '8+0 records in\n8+0 records out\n4096 bytes (4.1 kB, 4.0 KiB) copied, 1.47883 s, 2.8 kB/s\n'; <rc> = 0 (commands:93) 09:56:15 2017-01-29 09:54:02,394 DEBUG (mailbox-hsm) [storage.MailBox.HsmMailMonitor] FINISH thread <Thread(mailbox-hsm, started daemon 140445049673472)> (concurrent:186)

1 0

Fwd: SSO and the engine
by Piotr Kliczewski 27 Jan '17

27 Jan '17

I downgraded jdk and it did not help. My dnf says when I attempt to install as in the link: No package nss-3.27.0-1.1.fc25.x86_64 available. No package nss-softokn-3.27.0-1.0.fc25.x86_64 available. No package nss-softokn-freebl-3.27.0-1.0.fc25.x86_64 available. No package nss-sysinit-3.27.0-1.1.fc25.x86_64 available. No package nss-tools-3.27.0-1.1.fc25.x86_64 available. No package nss-util-3.27.0-1.0.fc25.x86_64 available. I am not able to downgrade nss due to conflicts with other packages.: On Fri, Jan 27, 2017 at 2:23 PM, Benny Zlotnik <bzlotnik(a)redhat.com> wrote: > You can also try downgrading the nss packages, see: > https://bugzilla.redhat.com/show_bug.cgi?id=1415137#c15 > > On Fri, Jan 27, 2017 at 3:18 PM, Piotr Kliczewski > <piotr.kliczewski(a)gmail.com> wrote: >> >> I was too fast to send the update. I am able to login now but I see >> core dump during host add: >> >> 2017-01-27 14:14:01,906+01 ERROR >> [org.ovirt.engine.core.bll.hostdeploy.AddVdsCommand] (default task-58) >> [20086bed-e76d-42ef-9ab1-30c8e965374b] Failed to establish session >> with host 'fedora': SSH session closed during connection >> 'root(a)192.168.1.102' >> 2017-01-27 14:14:01,907+01 WARN >> [org.ovirt.engine.core.bll.hostdeploy.AddVdsCommand] (default task-58) >> [20086bed-e76d-42ef-9ab1-30c8e965374b] Validation of action 'AddVds' >> failed for user admin@internal-authz. Reasons: >> VAR__ACTION__ADD,VAR__TYPE__HOST,$server >> 192.168.1.102,VDS_CANNOT_CONNECT_TO_SERVER >> # >> # A fatal error has been detected by the Java Runtime Environment: >> # >> # SIGSEGV (0xb) at pc=0x00007f7c9d773734, pid=20890, >> tid=0x00007f7c6c148700 >> # >> # JRE version: OpenJDK Runtime Environment (8.0_111-b16) (build >> 1.8.0_111-b16) >> # Java VM: OpenJDK 64-Bit Server VM (25.111-b16 mixed mode linux-amd64 >> compressed oops) >> # Problematic frame: >> # C [libc.so.6+0x14a734] __memcpy_avx_unaligned+0x2c4 >> # >> # Failed to write core dump. Core dumps have been disabled. To enable >> core dumping, try "ulimit -c unlimited" before starting Java again >> # >> # An error report file with more information is saved as: >> # /tmp/hs_err_pid20890.log >> # >> # If you would like to submit a bug report, please visit: >> # http://bugreport.java.com/bugreport/crash.jsp >> # >> ovirt-engine[20848] ERROR run:554 Error: process terminated with status >> code -6 >> >> 2017-01-27 14:14:01,756+01 INFO >> [org.apache.sshd.common.util.SecurityUtils] (default task-58) >> BouncyCastle not registered, using the default JCE provider >> 2017-01-27 14:14:01,870+01 INFO >> [org.apache.sshd.client.session.ClientSessionImpl] >> (sshd-SshClient[26c9f7da]-nio2-thread-1) Client session created >> 2017-01-27 14:14:01,885+01 INFO >> [org.apache.sshd.client.session.ClientSessionImpl] >> (sshd-SshClient[26c9f7da]-nio2-thread-1) Server version string: >> SSH-2.0-OpenSSH_7.2 >> 2017-01-27 14:14:01,886+01 INFO >> [org.apache.sshd.client.session.ClientSessionImpl] >> (sshd-SshClient[26c9f7da]-nio2-thread-1) Kex: server->client >> aes128-ctr hmac-sha2-256 none >> 2017-01-27 14:14:01,886+01 INFO >> [org.apache.sshd.client.session.ClientSessionImpl] >> (sshd-SshClient[26c9f7da]-nio2-thread-1) Kex: client->server >> aes128-ctr hmac-sha2-256 none >> 2017-01-27 14:14:01,896+01 WARN >> [org.apache.sshd.client.session.ClientSessionImpl] >> (sshd-SshClient[26c9f7da]-nio2-thread-1) Exception caught: >> java.security.ProviderException: java.lang.NegativeArraySizeException >> at >> sun.security.ec.ECKeyPairGenerator.generateKeyPair(ECKeyPairGenerator.java:147) >> at >> java.security.KeyPairGenerator$Delegate.generateKeyPair(KeyPairGenerator.java:703) >> [rt.jar:1.8.0_111] >> at org.apache.sshd.common.kex.ECDH.getE(ECDH.java:59) >> at >> org.apache.sshd.client.kex.AbstractDHGClient.init(AbstractDHGClient.java:78) >> at >> org.apache.sshd.common.session.AbstractSession.doHandleMessage(AbstractSession.java:359) >> at >> org.apache.sshd.common.session.AbstractSession.handleMessage(AbstractSession.java:295) >> at >> org.apache.sshd.client.session.ClientSessionImpl.handleMessage(ClientSessionImpl.java:256) >> at >> org.apache.sshd.common.session.AbstractSession.decode(AbstractSession.java:731) >> at >> org.apache.sshd.common.session.AbstractSession.messageReceived(AbstractSession.java:277) >> at >> org.apache.sshd.common.AbstractSessionIoHandler.messageReceived(AbstractSessionIoHandler.java:54) >> at >> org.apache.sshd.common.io.nio2.Nio2Session$1.onCompleted(Nio2Session.java:187) >> at >> org.apache.sshd.common.io.nio2.Nio2Session$1.onCompleted(Nio2Session.java:173) >> at >> org.apache.sshd.common.io.nio2.Nio2CompletionHandler$1.run(Nio2CompletionHandler.java:32) >> at java.security.AccessController.doPrivileged(Native Method) >> [rt.jar:1.8.0_111] >> at >> org.apache.sshd.common.io.nio2.Nio2CompletionHandler.completed(Nio2CompletionHandler.java:30) >> at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126) [rt.jar:1.8.0_111] >> at sun.nio.ch.Invoker.invokeDirect(Invoker.java:157) [rt.jar:1.8.0_111] >> at >> sun.nio.ch.UnixAsynchronousSocketChannelImpl.implRead(UnixAsynchronousSocketChannelImpl.java:553) >> [rt.jar:1.8.0_111] >> at >> sun.nio.ch.AsynchronousSocketChannelImpl.read(AsynchronousSocketChannelImpl.java:276) >> [rt.jar:1.8.0_111] >> at >> sun.nio.ch.AsynchronousSocketChannelImpl.read(AsynchronousSocketChannelImpl.java:297) >> [rt.jar:1.8.0_111] >> at >> java.nio.channels.AsynchronousSocketChannel.read(AsynchronousSocketChannel.java:420) >> [rt.jar:1.8.0_111] >> at >> org.apache.sshd.common.io.nio2.Nio2Session.startReading(Nio2Session.java:173) >> at >> org.apache.sshd.common.io.nio2.Nio2Connector$1.onCompleted(Nio2Connector.java:53) >> at >> org.apache.sshd.common.io.nio2.Nio2Connector$1.onCompleted(Nio2Connector.java:46) >> at >> org.apache.sshd.common.io.nio2.Nio2CompletionHandler$1.run(Nio2CompletionHandler.java:32) >> at java.security.AccessController.doPrivileged(Native Method) >> [rt.jar:1.8.0_111] >> at >> org.apache.sshd.common.io.nio2.Nio2CompletionHandler.completed(Nio2CompletionHandler.java:30) >> at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126) [rt.jar:1.8.0_111] >> at sun.nio.ch.Invoker$2.run(Invoker.java:218) [rt.jar:1.8.0_111] >> at >> sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112) >> [rt.jar:1.8.0_111] >> at >> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) >> [rt.jar:1.8.0_111] >> at >> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) >> [rt.jar:1.8.0_111] >> at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_111] >> Caused by: java.lang.NegativeArraySizeException >> at sun.security.ec.ECKeyPairGenerator.generateECKeyPair(Native Method) >> at >> sun.security.ec.ECKeyPairGenerator.generateKeyPair(ECKeyPairGenerator.java:128) >> ... 32 more >> >> On Fri, Jan 27, 2017 at 1:56 PM, Piotr Kliczewski >> <piotr.kliczewski(a)gmail.com> wrote: >> > Thank you Juan, It fixed my issue >> > >> > I updated java.security and changed: >> > >> > from >> > >> > jdk.tls.disabledAlgorithms=SSLv3, RC4, MD5withRSA, DH keySize < 768 >> > >> > to >> > >> > jdk.tls.disabledAlgorithms=SSLv3, DH keySize < 768, EC, ECDHE, ECDH >> > >> > Thanks, >> > Piotr >> > >> > On Fri, Jan 27, 2017 at 1:42 PM, Juan Hernández <jhernand(a)redhat.com> >> > wrote: >> >> See this Piotr: >> >> >> >> >> >> >> >> http://post-office.corp.redhat.com/archives/rhev-devel/2017-January/msg0026… >> >> >> >> Benny, may be worth publishing it to the upstream devel list. >> >> >> >> On 01/27/2017 01:35 PM, Piotr Kliczewski wrote: >> >>> All, >> >>> >> >>> I pulled the latest source from master and rebuilt my engine. Every >> >>> time I attempt to login I see: >> >>> >> >>> 2017-01-27 13:22:51,403+01 INFO >> >>> [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default >> >>> task-54) [] User admin@internal successfully logged in with scopes: >> >>> ovirt-app-admin ovirt-app-api ovirt-app-portal >> >>> ovirt-ext=auth:sequence-priority=~ ovirt-ext=revoke:revoke-all >> >>> ovirt-ext=token-info:authz-search >> >>> ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate >> >>> ovirt-ext=token:password-access >> >>> # >> >>> # A fatal error has been detected by the Java Runtime Environment: >> >>> # >> >>> # SIGSEGV (0xb) at pc=0x00007f514eb45734, pid=2519, >> >>> tid=0x00007f51119a6700 >> >>> # >> >>> # JRE version: OpenJDK Runtime Environment (8.0_111-b16) (build >> >>> 1.8.0_111-b16) >> >>> # Java VM: OpenJDK 64-Bit Server VM (25.111-b16 mixed mode linux-amd64 >> >>> compressed oops) >> >>> # Problematic frame: >> >>> # C [libc.so.6+0x14a734] __memcpy_avx_unaligned+0x2c4 >> >>> # >> >>> # Failed to write core dump. Core dumps have been disabled. To enable >> >>> core dumping, try "ulimit -c unlimited" before starting Java again >> >>> # >> >>> # An error report file with more information is saved as: >> >>> # /tmp/hs_err_pid2519.log >> >>> # >> >>> # If you would like to submit a bug report, please visit: >> >>> # http://bugreport.java.com/bugreport/crash.jsp >> >>> # >> >>> ovirt-engine[2471] ERROR run:554 Error: process terminated with status >> >>> code -6 >> >>> >> >>> I enabled ssl debug to find: >> >>> >> >>> 2017-01-27 13:22:37,641+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, fatal error: 80: problem unwrapping net record >> >>> 2017-01-27 13:22:37,642+01 INFO [stdout] (default I/O-2) >> >>> java.lang.RuntimeException: java.lang.NegativeArraySizeException >> >>> 2017-01-27 13:22:37,642+01 INFO [stdout] (default I/O-2) %% >> >>> Invalidated: [Session-1, SSL_NULL_WITH_NULL_NULL] >> >>> 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, SEND TLSv1.2 ALERT: fatal, description = internal_error >> >>> 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, WRITE: TLSv1.2 Alert, length = 2 >> >>> 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, called closeInbound() >> >>> 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, fatal: engine already closed. Rethrowing >> >>> javax.net.ssl.SSLException: Inbound closed before receiving peer's >> >>> close_notify: possible truncation attack? >> >>> 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, called closeOutbound() >> >>> 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default >> >>> I/O-2, closeOutboundInternal() >> >>> 2017-01-27 13:22:37,644+01 INFO [stdout] (default task-1) default >> >>> task-1, received EOFException: error >> >>> 2017-01-27 13:22:37,644+01 INFO [stdout] (default task-1) default >> >>> task-1, handling exception: javax.net.ssl.SSLHandshakeException: >> >>> Remote host closed connection during handshake >> >>> 2017-01-27 13:22:37,645+01 INFO [stdout] (default task-1) default >> >>> task-1, SEND TLSv1.2 ALERT: fatal, description = handshake_failure >> >>> 2017-01-27 13:22:37,645+01 INFO [stdout] (default task-1) default >> >>> task-1, WRITE: TLSv1.2 Alert, length = 2 >> >>> 2017-01-27 13:22:37,645+01 INFO [stdout] (default task-1) [Raw >> >>> write]: length = 7 >> >>> 2017-01-27 13:22:37,647+01 INFO [stdout] (default task-1) 0000: 15 03 >> >>> 03 00 02 02 28 ......( >> >>> 2017-01-27 13:22:37,647+01 INFO [stdout] (default task-1) default >> >>> task-1, called closeSocket() >> >>> 2017-01-27 13:22:37,644+01 ERROR [org.xnio.nio] (default I/O-2) >> >>> XNIO000011: Task io.undertow.protocols.ssl.SslConduit$5$1@6d665208 >> >>> failed with an exception: java.lang.RuntimeException: >> >>> java.lang.NegativeArraySizeException >> >>> at sun.security.ssl.Handshaker.checkThrown(Handshaker.java:1429) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.SSLEngineImpl.checkTaskThrown(SSLEngineImpl.java:535) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.SSLEngineImpl.readNetRecord(SSLEngineImpl.java:813) >> >>> [jsse.jar:1.8.0_111] >> >>> at sun.security.ssl.SSLEngineImpl.unwrap(SSLEngineImpl.java:781) >> >>> [jsse.jar:1.8.0_111] >> >>> at javax.net.ssl.SSLEngine.unwrap(SSLEngine.java:624) >> >>> [rt.jar:1.8.0_111] >> >>> at io.undertow.protocols.ssl.SslConduit.doUnwrap(SslConduit.java:742) >> >>> at >> >>> io.undertow.protocols.ssl.SslConduit.doHandshake(SslConduit.java:639) >> >>> at io.undertow.protocols.ssl.SslConduit.access$900(SslConduit.java:63) >> >>> at io.undertow.protocols.ssl.SslConduit$5$1.run(SslConduit.java:1035) >> >>> at org.xnio.nio.WorkerThread.safeRun(WorkerThread.java:588) >> >>> [xnio-nio-3.4.0.Final.jar:3.4.0.Final] >> >>> at org.xnio.nio.WorkerThread.run(WorkerThread.java:468) >> >>> [xnio-nio-3.4.0.Final.jar:3.4.0.Final] >> >>> Caused by: java.security.ProviderException: >> >>> java.lang.NegativeArraySizeException >> >>> at >> >>> sun.security.ec.ECKeyPairGenerator.generateKeyPair(ECKeyPairGenerator.java:147) >> >>> at >> >>> java.security.KeyPairGenerator$Delegate.generateKeyPair(KeyPairGenerator.java:703) >> >>> [rt.jar:1.8.0_111] >> >>> at sun.security.ssl.ECDHCrypt.<init>(ECDHCrypt.java:64) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.ServerHandshaker.setupEphemeralECDHKeys(ServerHandshaker.java:1432) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.ServerHandshaker.trySetCipherSuite(ServerHandshaker.java:1219) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.ServerHandshaker.chooseCipherSuite(ServerHandshaker.java:1023) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.ServerHandshaker.clientHello(ServerHandshaker.java:738) >> >>> [jsse.jar:1.8.0_111] >> >>> at >> >>> sun.security.ssl.ServerHandshaker.processMessage(ServerHandshaker.java:221) >> >>> [jsse.jar:1.8.0_111] >> >>> at sun.security.ssl.Handshaker.processLoop(Handshaker.java:979) >> >>> [jsse.jar:1.8.0_111] >> >>> at sun.security.ssl.Handshaker$1.run(Handshaker.java:919) >> >>> [jsse.jar:1.8.0_111] >> >>> at sun.security.ssl.Handshaker$1.run(Handshaker.java:916) >> >>> [jsse.jar:1.8.0_111] >> >>> at java.security.AccessController.doPrivileged(Native Method) >> >>> [rt.jar:1.8.0_111] >> >>> at sun.security.ssl.Handshaker$DelegatedTask.run(Handshaker.java:1369) >> >>> [jsse.jar:1.8.0_111] >> >>> at io.undertow.protocols.ssl.SslConduit$5.run(SslConduit.java:1023) >> >>> at >> >>> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) >> >>> [rt.jar:1.8.0_111] >> >>> at >> >>> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) >> >>> [rt.jar:1.8.0_111] >> >>> at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_111] >> >>> Caused by: java.lang.NegativeArraySizeException >> >>> at sun.security.ec.ECKeyPairGenerator.generateECKeyPair(Native Method) >> >>> at >> >>> sun.security.ec.ECKeyPairGenerator.generateKeyPair(ECKeyPairGenerator.java:128) >> >>> ... 16 more >> >>> >> >>> Are we aware of the issue? Is there any workaround? >> >>> >> >>> I am using fedora 24 with all recent updates applied. >> >>> >> >>> Thanks, >> >>> Piotr >> >>> >> >>> >> >>> >> >>> _______________________________________________ >> >>> Devel mailing list >> >>> Devel(a)ovirt.org >> >>> http://lists.ovirt.org/mailman/listinfo/devel >> >>> >> >> > >

1 1

SSO and the engine
by Piotr Kliczewski 27 Jan '17

27 Jan '17

All, I pulled the latest source from master and rebuilt my engine. Every time I attempt to login I see: 2017-01-27 13:22:51,403+01 INFO [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-54) [] User admin@internal successfully logged in with scopes: ovirt-app-admin ovirt-app-api ovirt-app-portal ovirt-ext=auth:sequence-priority=~ ovirt-ext=revoke:revoke-all ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate ovirt-ext=token:password-access # # A fatal error has been detected by the Java Runtime Environment: # # SIGSEGV (0xb) at pc=0x00007f514eb45734, pid=2519, tid=0x00007f51119a6700 # # JRE version: OpenJDK Runtime Environment (8.0_111-b16) (build 1.8.0_111-b16) # Java VM: OpenJDK 64-Bit Server VM (25.111-b16 mixed mode linux-amd64 compressed oops) # Problematic frame: # C [libc.so.6+0x14a734] __memcpy_avx_unaligned+0x2c4 # # Failed to write core dump. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again # # An error report file with more information is saved as: # /tmp/hs_err_pid2519.log # # If you would like to submit a bug report, please visit: # http://bugreport.java.com/bugreport/crash.jsp # ovirt-engine[2471] ERROR run:554 Error: process terminated with status code -6 I enabled ssl debug to find: 2017-01-27 13:22:37,641+01 INFO [stdout] (default I/O-2) default I/O-2, fatal error: 80: problem unwrapping net record 2017-01-27 13:22:37,642+01 INFO [stdout] (default I/O-2) java.lang.RuntimeException: java.lang.NegativeArraySizeException 2017-01-27 13:22:37,642+01 INFO [stdout] (default I/O-2) %% Invalidated: [Session-1, SSL_NULL_WITH_NULL_NULL] 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default I/O-2, SEND TLSv1.2 ALERT: fatal, description = internal_error 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default I/O-2, WRITE: TLSv1.2 Alert, length = 2 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default I/O-2, called closeInbound() 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default I/O-2, fatal: engine already closed. Rethrowing javax.net.ssl.SSLException: Inbound closed before receiving peer's close_notify: possible truncation attack? 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default I/O-2, called closeOutbound() 2017-01-27 13:22:37,643+01 INFO [stdout] (default I/O-2) default I/O-2, closeOutboundInternal() 2017-01-27 13:22:37,644+01 INFO [stdout] (default task-1) default task-1, received EOFException: error 2017-01-27 13:22:37,644+01 INFO [stdout] (default task-1) default task-1, handling exception: javax.net.ssl.SSLHandshakeException: Remote host closed connection during handshake 2017-01-27 13:22:37,645+01 INFO [stdout] (default task-1) default task-1, SEND TLSv1.2 ALERT: fatal, description = handshake_failure 2017-01-27 13:22:37,645+01 INFO [stdout] (default task-1) default task-1, WRITE: TLSv1.2 Alert, length = 2 2017-01-27 13:22:37,645+01 INFO [stdout] (default task-1) [Raw write]: length = 7 2017-01-27 13:22:37,647+01 INFO [stdout] (default task-1) 0000: 15 03 03 00 02 02 28 ......( 2017-01-27 13:22:37,647+01 INFO [stdout] (default task-1) default task-1, called closeSocket() 2017-01-27 13:22:37,644+01 ERROR [org.xnio.nio] (default I/O-2) XNIO000011: Task io.undertow.protocols.ssl.SslConduit$5$1@6d665208 failed with an exception: java.lang.RuntimeException: java.lang.NegativeArraySizeException at sun.security.ssl.Handshaker.checkThrown(Handshaker.java:1429) [jsse.jar:1.8.0_111] at sun.security.ssl.SSLEngineImpl.checkTaskThrown(SSLEngineImpl.java:535) [jsse.jar:1.8.0_111] at sun.security.ssl.SSLEngineImpl.readNetRecord(SSLEngineImpl.java:813) [jsse.jar:1.8.0_111] at sun.security.ssl.SSLEngineImpl.unwrap(SSLEngineImpl.java:781) [jsse.jar:1.8.0_111] at javax.net.ssl.SSLEngine.unwrap(SSLEngine.java:624) [rt.jar:1.8.0_111] at io.undertow.protocols.ssl.SslConduit.doUnwrap(SslConduit.java:742) at io.undertow.protocols.ssl.SslConduit.doHandshake(SslConduit.java:639) at io.undertow.protocols.ssl.SslConduit.access$900(SslConduit.java:63) at io.undertow.protocols.ssl.SslConduit$5$1.run(SslConduit.java:1035) at org.xnio.nio.WorkerThread.safeRun(WorkerThread.java:588) [xnio-nio-3.4.0.Final.jar:3.4.0.Final] at org.xnio.nio.WorkerThread.run(WorkerThread.java:468) [xnio-nio-3.4.0.Final.jar:3.4.0.Final] Caused by: java.security.ProviderException: java.lang.NegativeArraySizeException at sun.security.ec.ECKeyPairGenerator.generateKeyPair(ECKeyPairGenerator.java:147) at java.security.KeyPairGenerator$Delegate.generateKeyPair(KeyPairGenerator.java:703) [rt.jar:1.8.0_111] at sun.security.ssl.ECDHCrypt.<init>(ECDHCrypt.java:64) [jsse.jar:1.8.0_111] at sun.security.ssl.ServerHandshaker.setupEphemeralECDHKeys(ServerHandshaker.java:1432) [jsse.jar:1.8.0_111] at sun.security.ssl.ServerHandshaker.trySetCipherSuite(ServerHandshaker.java:1219) [jsse.jar:1.8.0_111] at sun.security.ssl.ServerHandshaker.chooseCipherSuite(ServerHandshaker.java:1023) [jsse.jar:1.8.0_111] at sun.security.ssl.ServerHandshaker.clientHello(ServerHandshaker.java:738) [jsse.jar:1.8.0_111] at sun.security.ssl.ServerHandshaker.processMessage(ServerHandshaker.java:221) [jsse.jar:1.8.0_111] at sun.security.ssl.Handshaker.processLoop(Handshaker.java:979) [jsse.jar:1.8.0_111] at sun.security.ssl.Handshaker$1.run(Handshaker.java:919) [jsse.jar:1.8.0_111] at sun.security.ssl.Handshaker$1.run(Handshaker.java:916) [jsse.jar:1.8.0_111] at java.security.AccessController.doPrivileged(Native Method) [rt.jar:1.8.0_111] at sun.security.ssl.Handshaker$DelegatedTask.run(Handshaker.java:1369) [jsse.jar:1.8.0_111] at io.undertow.protocols.ssl.SslConduit$5.run(SslConduit.java:1023) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [rt.jar:1.8.0_111] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [rt.jar:1.8.0_111] at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_111] Caused by: java.lang.NegativeArraySizeException at sun.security.ec.ECKeyPairGenerator.generateECKeyPair(Native Method) at sun.security.ec.ECKeyPairGenerator.generateKeyPair(ECKeyPairGenerator.java:128) ... 16 more Are we aware of the issue? Is there any workaround? I am using fedora 24 with all recent updates applied. Thanks, Piotr

1 0

Re: [ovirt-devel] [ovirt-users] Translate hint
by Yedidyah Bar David 27 Jan '17

27 Jan '17

On Wed, Jan 25, 2017 at 6:28 PM, Gianluca Cecchi <gianluca.cecchi(a)gmail.com> wrote: > Hello, > I'm checking Italian Translation in 4.0.6 and completing it for 4.1 (now at > 92%). > Suppose I found an untranslated / bad translated word: what is the best and > quick way to go to Zanata and find the reference for it and correct? > For example in 4.0.6 I'm in dashboard and at bottom I have the three labels > > Alert, Events, Tasks > > I click ALerts and then I want to dismiss an Alert because it happened while > I was configuring Power Management: I faked the password to verify that the > test failed and I got the alert > > Power Management test failed for Host ..... > > I rght click on the alert line and I see three options and the first and > third one are untranslated > > they are > > Dismiss Alert > Display All > > (I'm going to send a separate e-mail to ask about alerts and events in > general). > > If I go to Zanata, select oVirt 4.0 and Italian language line and use the > search function I don't find anything.... > I see there is an option to > Download All for Offline Translation > and > Export Italian Documents to TMX > I have not tried them yet, but I would prefer to directly correct online > while I find some things to correct. Adding devel and Yuko. -- Didi

4 3

[ANN] oVirt 4.1.0 Second Release Candidate is now available
by Sandro Bonazzola 26 Jan '17

26 Jan '17

The oVirt Project is pleased to announce the availability of the Second Release candidate of oVirt 4.1.0 for testing, as of January 26th, 2016 This is pre-release software. Please take a look at our community page[1] to know how to ask questions and interact with developers and users. All issues or bugs should be reported via oVirt Bugzilla[2]. This pre-release should not to be used in production. This update is the second release candidate of the 4.1 release series. 4.1.0 brings more than 260 enhancements and 790 bugfixes, including 340 high or urgent severity fixes, on top of oVirt 4.0 series See the release notes [3] for installation / upgrade instructions and a list of new features and bugs fixed. This release is available now for: * Fedora 24 (tech preview) * Red Hat Enterprise Linux 7.3 or later * CentOS Linux (or similar) 7.3 or later This release supports Hypervisor Hosts running: * Red Hat Enterprise Linux 7.3 or later * CentOS Linux (or similar) 7.3 or later * Fedora 24 (tech preview) * oVirt Node 4.1 See the release notes draft [3] for installation / upgrade instructions and a list of new features and bugs fixed. Notes: - oVirt Live iso is already available[5] - oVirt Node NG iso is already available[5] - Hosted Engine appliance is already available. - oVirt Windows Guest Tools iso is already available[5] A release management page including planned schedule is also available[4] Additional Resources: * Read more about the oVirt 4.1.0 release highlights: http://www.ovirt.org/release/4.1.0/ * Get more oVirt Project updates on Twitter: https://twitter.com/ovirt * Check out the latest project news on the oVirt blog: http://www.ovirt.org/blog/ [1] https://www.ovirt.org/community/ [2] https://bugzilla.redhat.com/enter_bug.cgi?classification=oVirt [3] http://www.ovirt.org/release/4.1.0/ [4] http://www.ovirt.org/develop/release-management/releases/4.1/release-manage… [5] http://resources.ovirt.org/pub/ovirt-4.1-pre/iso/ -- Sandro Bonazzola Better technology. Faster innovation. Powered by community collaboration. See how it works at redhat.com

1 0

python-dateutil required for engine development environment
by Martin Perina 26 Jan '17

26 Jan '17

Hi, 2 days ago we have merged [1], which adds python-dateutil as a required package. For RPM installation it's installed automatically, but if you are using engine development environment, you need to install the package manually: yum install python-dateutil Sorry for late announcement. Martin Perina [1] https://gerrit.ovirt.org/70114

1 0

Missing vdsm snapshots for Fedoras
by Jakub Niedermertl 26 Jan '17

26 Jan '17

Hi all, there are no vdsm packages in http://resources.ovirt.org/pub/ovirt-master-snapshot/rpm/fc24/x86_64/ and in http://resources.ovirt.org/pub/ovirt-master-snapshot/rpm/fc25/x86_64/ but there are vdsm packages in corresponding EL directories. Is it intentional? Do we still provide ovirt vdsm snapshots? Thanks Jakub

5 4

[URGENT][ACTION REQUIRED] Repository closure failure for oVirt 4.1.0 RC2
by Sandro Bonazzola 26 Jan '17

26 Jan '17

*00:00:44.789* package: ovirt-engine-backend-4.1.0.3-1.fc24.noarch from check-custom-fc24*00:00:44.789* unresolved deps: *00:00:44.789* vdsm-jsonrpc-java >= 0:1.3.8 [sbonazzo@sbonazzo vdsm-jsonrpc-java] [vdsm-jsonrpc-java:ovirt-4.1]$ git tag --list |grep 1.3 v1.3.3 v1.3.4 v1.3.5 v1.3.6 v1.3.7 We really need a test in check-patch.sh which build the rpms and install them so we can detect these errors earlier. Piotr please provide vdsm-jsonrpc-java v.1.3.8, thanks -- Sandro Bonazzola Better technology. Faster innovation. Powered by community collaboration. See how it works at redhat.com

3 6

[ OST Failure Report ] [ oVirt master ] [ 25.1.17 ] [hotplug_disk]
by Gil Shinar 26 Jan '17

26 Jan '17

Hi, The test in $subject has failed. Below please find exception I have found in engine.log. {"jsonrpc": "2.0", "id": "4b8eb810-c52d-4c92-a792-e554f87c9493", "error": {"message": "Cannot deactivate Logical Volume: ('General Storage Exception: (\"5 [] [\\' WARNING: Not using lvmetad because config setting use_lvmetad=0.\\', \\' WARNING: To avoid corruption, rescan devices to make changes visible (pvscan --cache).\\', \\' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5 in use.\\', \\' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/ab4e8962-6196-485e-be2a-d5791a38eaeb in use.\\']\\\\nf9dce023-0282-4185-9ad9-fe71c3975106/[\\'778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5\\', \\'ab4e8962-6196-485e-be2a-d5791a38eaeb\\']\",)',)", "code": 552}}� 2017-01-25 05:05:49,037-05 DEBUG [org.ovirt.vdsm.jsonrpc.client.internal.ResponseWorker] (ResponseWorker) [] Message received: {"jsonrpc": "2.0", "id": "4b8eb810-c52d-4c92-a792-e554f87c9493", "error": {"message": "Cannot deactivate Logical Volume: ('General Storage Exception: (\"5 [] [\\' WARNING: Not using lvmetad because config setting use_lvmetad=0.\\', \\' WARNING: To avoid corruption, rescan devices to make changes visible (pvscan --cache).\\', \\' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5 in use.\\', \\' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/ab4e8962-6196-485e-be2a-d5791a38eaeb in use.\\']\\\\nf9dce023-0282-4185-9ad9-fe71c3975106/[\\'778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5\\', \\'ab4e8962-6196-485e-be2a-d5791a38eaeb\\']\",)',)", "code": 552}} 2017-01-25 05:05:49,047-05 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler5) [59ab00f1] EVENT_ID: VDS_BROKER_COMMAND_FAILURE(10,802), Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VDSM lago-basic-suite-master-host0 command TeardownImageVDS failed: Cannot deactivate Logical Volume: ('General Storage Exception: ("5 [] [\' WARNING: Not using lvmetad because config setting use_lvmetad=0.\', \' WARNING: To avoid corruption, rescan devices to make changes visible (pvscan --cache).\', \' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5 in use.\', \' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/ab4e8962-6196-485e-be2a-d5791a38eaeb in use.\']\\nf9dce023-0282-4185-9ad9-fe71c3975106/[\'778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5\', \'ab4e8962-6196-485e-be2a-d5791a38eaeb\']",)',) 2017-01-25 05:05:49,047-05 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (DefaultQuartzScheduler5) [59ab00f1] Command 'TeardownImageVDSCommand(HostName = lago-basic-suite-master-host0, ImageActionsVDSCommandParameters:{runAsync='true', hostId='60e52527-b637-445c-b408-0275d347e76a'})' execution failed: VDSGenericException: VDSErrorException: Failed in vdscommand to TeardownImageVDS, error = Cannot deactivate Logical Volume: ('General Storage Exception: ("5 [] [\' WARNING: Not using lvmetad because config setting use_lvmetad=0.\', \' WARNING: To avoid corruption, rescan devices to make changes visible (pvscan --cache).\', \' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5 in use.\', \' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/ab4e8962-6196-485e-be2a-d5791a38eaeb in use.\']\\nf9dce023-0282-4185-9ad9-fe71c3975106/[\'778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5\', \'ab4e8962-6196-485e-be2a-d5791a38eaeb\']",)',) 2017-01-25 05:05:49,047-05 DEBUG [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (DefaultQuartzScheduler5) [59ab00f1] Exception: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed in vdscommand to TeardownImageVDS, error = Cannot deactivate Logical Volume: ('General Storage Exception: ("5 [] [\' WARNING: Not using lvmetad because config setting use_lvmetad=0.\', \' WARNING: To avoid corruption, rescan devices to make changes visible (pvscan --cache).\', \' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5 in use.\', \' Logical volume f9dce023-0282-4185-9ad9-fe71c3975106/ab4e8962-6196-485e-be2a-d5791a38eaeb in use.\']\\nf9dce023-0282-4185-9ad9-fe71c3975106/[\'778cbc5b-a9df-46d7-bc80-1a66f7d3e2b5\', \'ab4e8962-6196-485e-be2a-d5791a38eaeb\']",)',) at org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase.proceedProxyReturnValue(BrokerCommandBase.java:182) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.vdsbroker.ImageActionsVDSCommandBase.executeVdsBrokerCommand(ImageActionsVDSCommandBase.java:20) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:111) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:73) [vdsbroker.jar:] at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:33) [dal.jar:] at org.ovirt.engine.core.vdsbroker.vdsbroker.DefaultVdsCommandExecutor.execute(DefaultVdsCommandExecutor.java:14) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.ResourceManager.runVdsCommand(ResourceManager.java:407) [vdsbroker.jar:] at org.ovirt.engine.core.bll.VDSBrokerFrontendImpl.runVdsCommand(VDSBrokerFrontendImpl.java:33) [bll.jar:] at org.ovirt.engine.core.bll.storage.disk.image.ImagesHandler.teardownImage(ImagesHandler.java:1007) [bll.jar:] at org.ovirt.engine.core.bll.storage.disk.image.ImagesHandler.getQemuImageInfoFromVdsm(ImagesHandler.java:856) [bll.jar:] at org.ovirt.engine.core.bll.storage.disk.image.BaseImagesCommand.endSuccessfully(BaseImagesCommand.java:367) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.internalEndSuccessfully(CommandBase.java:736) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.endActionInTransactionScope(CommandBase.java:694) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2057) [bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:164) [utils.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:103) [utils.jar:] at org.ovirt.engine.core.bll.CommandBase.endAction(CommandBase.java:559) [bll.jar:] at org.ovirt.engine.core.bll.Backend.endAction(Backend.java:536) [bll.jar:] Link to suspected patches: https://gerrit.ovirt.org/#/c/71132/ Link to Job: http://jenkins.ovirt.org/job/test-repo_ovirt_experimental_master/4950/ Link to all logs: http://jenkins.ovirt.org/job/test-repo_ovirt_experimental_master/4951/artif… Thanks Gil

4 4

[ OST Failure Report ] [ oVirt master ] [25.01.17] [add_hosts]
by Gil Shinar 26 Jan '17

26 Jan '17

Hi, vdsm-cli has been removed from VDSM rpms but hadn't been removed from add hosts. Here is the error message: 2017-01-25 10:59:44 DEBUG otopi.plugins.otopi.packagers.yumpackager yumpackager.verbose:76 Yum Building transaction 2017-01-25 10:59:47 ERROR otopi.plugins.otopi.packagers.yumpackager yumpackager.error:85 Yum [u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-xmlrpc = 4.20.0-261.gitabb73a5.el7.centos', u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-client = 4.20.0-261.gitabb73a5.el7.centos', u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-python = 4.20.0-261.gitabb73a5.el7.centos'] 2017-01-25 10:59:47 DEBUG otopi.context context._executeMethod:142 method exception Traceback (most recent call last): File "/tmp/ovirt-9WzrERSugT/pythonlib/otopi/context.py", line 132, in _executeMethod method['method']() File "/tmp/ovirt-9WzrERSugT/otopi-plugins/otopi/packagers/yumpackager.py", line 253, in _packages if self._miniyum.buildTransaction(): File "/tmp/ovirt-9WzrERSugT/pythonlib/otopi/miniyum.py", line 919, in buildTransaction raise yum.Errors.YumBaseError(msg) YumBaseError: [u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-xmlrpc = 4.20.0-261.gitabb73a5.el7.centos', u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-client = 4.20.0-261.gitabb73a5.el7.centos', u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-python = 4.20.0-261.gitabb73a5.el7.centos'] 2017-01-25 10:59:47 ERROR otopi.context context._executeMethod:151 Failed to execute stage 'Package installation': [u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-xmlrpc = 4.20.0-261.gitabb73a5.el7.centos', u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-client = 4.20.0-261.gitabb73a5.el7.centos', u'vdsm-cli-4.20.0-261.gitabb73a5.el7.centos.noarch requires vdsm-python = 4.20.0-261.gitabb73a5.el7.centos'] 2017-01-25 10:59:47 DEBUG otopi.transaction transaction.abort:119 aborting 'Yum Transaction' 2017-01-25 10:59:47 INFO otopi.plugins.otopi.packagers.yumpackager yumpackager.info:80 Yum Performing yum transaction rollback Link to suspected patches: https://gerrit.ovirt.org/#/c/68721/ Link to Job: http://jenkins.ovirt.org/job/test-repo_ovirt_experimental_master/4962/ Link to all logs: http://jenkins.ovirt.org/job/test-repo_ovirt_experimental_master/4962/artif… Thanks Gil

5 7