Build failed in Jenkins: ttcn3-ggsn-test-kernel-torvalds #312

See https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/312/display/redirect

Changes:

------------------------------------------ [...truncated 278.48 KiB...] [ 0.568302] PM: RTC time: 10:39:25, date: 2025-03-27 [ 0.572092] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.574427] audit: initializing netlink subsys (disabled) [ 0.575881] audit: type=2000 audit(1743071964.474:1): state=initialized audit_enabled=0 res=1 [ 0.579743] thermal_sys: Registered thermal governor 'step_wise' [ 0.580092] cpuidle: using governor menu [ 0.582991] PCI: Using configuration type 1 for base access [ 0.584697] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. [ 0.587562] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages [ 0.587648] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page [ 0.597000] ACPI: Added _OSI(Module Device) [ 0.597065] ACPI: Added _OSI(Processor Device) [ 0.597108] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.597148] ACPI: Added _OSI(Processor Aggregator Device) [ 0.617617] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.641629] ACPI: Interpreter enabled [ 0.643611] ACPI: PM: (supports S0 S3 S4 S5) [ 0.643680] ACPI: Using IOAPIC for interrupt routing [ 0.644023] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.644108] PCI: Using E820 reservations for host bridge windows [ 0.645993] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.689752] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.690361] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.690597] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.691169] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge [ 0.697266] PCI host bridge to bus 0000:00 [ 0.697504] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.697682] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.697734] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.697823] pci_bus 0000:00: root bus resource [mem 0x20000000-0xfebfffff window] [ 0.697876] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] [ 0.698042] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.699203] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint [ 0.709537] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint [ 0.714839] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint [ 0.715278] pci 0000:00:01.1: BAR 4 [io 0xc060-0xc06f] [ 0.715373] pci 0000:00:01.1: BAR 0 [io 0x01f0-0x01f7]: legacy IDE quirk [ 0.715440] pci 0000:00:01.1: BAR 1 [io 0x03f6]: legacy IDE quirk [ 0.715514] pci 0000:00:01.1: BAR 2 [io 0x0170-0x0177]: legacy IDE quirk [ 0.715579] pci 0000:00:01.1: BAR 3 [io 0x0376]: legacy IDE quirk [ 0.715883] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint [ 0.716212] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.716355] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.716725] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint [ 0.717340] pci 0000:00:02.0: BAR 0 [io 0xc040-0xc05f] [ 0.717414] pci 0000:00:02.0: BAR 1 [mem 0xfebc0000-0xfebc0fff] [ 0.717501] pci 0000:00:02.0: BAR 4 [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.717625] pci 0000:00:02.0: ROM [mem 0xfeb80000-0xfebbffff pref] [ 0.723328] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200 conventional PCI endpoint [ 0.723955] pci 0000:00:03.0: BAR 0 [io 0xc000-0xc03f] [ 0.724021] pci 0000:00:03.0: BAR 1 [mem 0xfebc1000-0xfebc1fff] [ 0.724076] pci 0000:00:03.0: BAR 4 [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.732914] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.733383] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.733640] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.733879] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.734021] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.740799] iommu: Default domain type: Translated [ 0.740872] iommu: DMA domain TLB invalidation policy: lazy mode [ 0.741710] SCSI subsystem initialized [ 0.745722] libata version 3.00 loaded. [ 0.746388] pps_core: LinuxPPS API ver. 1 registered [ 0.746444] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 0.746559] PTP clock support registered [ 0.747085] EDAC MC: Ver: 3.0.0 [ 0.748778] Advanced Linux Sound Architecture Driver Initialized. [ 0.761926] NetLabel: Initializing [ 0.761976] NetLabel: domain hash size = 128 [ 0.762016] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.762670] NetLabel: unlabeled traffic allowed by default [ 0.771023] PCI: Using ACPI for IRQ routing [ 0.771171] PCI: pci_cache_line_size set to 64 bytes [ 0.771614] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.771826] e820: reserve RAM buffer [mem 0x1ffe0000-0x1fffffff] [ 0.772947] vgaarb: loaded [ 0.773935] hpet: 3 channels of 0 reserved for per-cpu timers [ 0.774175] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.774280] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.782297] clocksource: Switched to clocksource tsc-early [ 0.969199] VFS: Disk quotas dquot_6.6.0 [ 0.969334] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.972227] pnp: PnP ACPI init [ 0.974350] pnp 00:02: [dma 2] [ 0.975204] pnp: PnP ACPI: found 5 devices [ 0.994496] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.995012] NET: Registered PF_INET protocol family [ 0.995885] IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 1.000291] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes, linear) [ 1.000421] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 1.000541] TCP established hash table entries: 4096 (order: 3, 32768 bytes, linear) [ 1.000792] TCP bind hash table entries: 4096 (order: 5, 131072 bytes, linear) [ 1.000965] TCP: Hash tables configured (established 4096 bind 4096) [ 1.001505] UDP hash table entries: 256 (order: 2, 16384 bytes, linear) [ 1.001779] UDP-Lite hash table entries: 256 (order: 2, 16384 bytes, linear) [ 1.002705] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 1.004295] RPC: Registered named UNIX socket transport module. [ 1.004382] RPC: Registered udp transport module. [ 1.004427] RPC: Registered tcp transport module. [ 1.004468] RPC: Registered tcp-with-tls transport module. [ 1.004515] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.006210] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.006284] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.006338] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.006395] pci_bus 0000:00: resource 7 [mem 0x20000000-0xfebfffff window] [ 1.006450] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] [ 1.006847] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.006959] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.007069] PCI: CLS 0 bytes, default 64 [ 1.014380] Unpacking initramfs... [ 1.019044] Initialise system trusted keyrings [ 1.021082] workingset: timestamp_bits=56 max_order=17 bucket_order=0 [ 1.025901] NFS: Registering the id_resolver key type [ 1.026058] Key type id_resolver registered [ 1.026116] Key type id_legacy registered [ 1.027888] 9p: Installing v9fs 9p2000 file system support [ 1.118090] Key type asymmetric registered [ 1.118189] Asymmetric key parser 'x509' registered [ 1.118998] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) [ 1.119380] io scheduler mq-deadline registered [ 1.119462] io scheduler kyber registered [ 1.125651] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 1.131831] ACPI: button: Power Button [PWRF] [ 1.262277] ACPI: _SB_.LNKB: Enabled at IRQ 10 [ 1.335374] Freeing initrd memory: 7308K [ 1.341483] kworker/u4:2 (59) used greatest stack depth: 15056 bytes left [ 1.342383] kworker/u4:2 (55) used greatest stack depth: 15000 bytes left [ 1.342669] kworker/u4:2 (72) used greatest stack depth: 14264 bytes left [ 1.368349] ACPI: _SB_.LNKC: Enabled at IRQ 11 [ 1.370458] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 1.374226] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 1.381631] Non-volatile memory driver v1.3 [ 1.381742] Linux agpgart interface v0.103 [ 1.382731] ACPI: bus type drm_connector registered [ 1.397463] loop: module loaded [ 1.399720] ata_piix 0000:00:01.1: version 2.13 [ 1.406058] scsi host0: ata_piix [ 1.407602] scsi host1: ata_piix [ 1.408345] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14 lpm-pol 0 [ 1.408450] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15 lpm-pol 0 [ 1.415792] BUG: kernel NULL pointer dereference, address: 0000000000000000 [ 1.415792] #PF: supervisor read access in kernel mode [ 1.415792] #PF: error_code(0x0000) - not-present page [ 1.415792] PGD 0 P4D 0 [ 1.415792] Oops: Oops: 0000 [#1] SMP NOPTI [ 1.415792] CPU: 0 UID: 0 PID: 1 Comm: swapper/0 Not tainted 6.14.0-gf6e0150b2003 #1 PREEMPT(voluntary) [ 1.415792] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-debian-1.16.2-1 04/01/2014 [ 1.415792] RIP: 0010:msix_prepare_msi_desc+0x33/0x80 [ 1.415792] Code: 00 00 48 89 f0 48 8b 52 20 66 81 4e 54 01 01 c7 46 04 01 00 00 00 8b 8f 9c 03 00 00 89 4e 58 48 8b b7 a8 07 00 00 48 89 70 60 <8b> 0a 31 d2 81 e1 00 00 40 00 75 0c 0f b6 50 55 d0 ea 83 f2 01 83 [ 1.415792] RSP: 0018:ffffc90000013a00 EFLAGS: 00000202 [ 1.415792] RAX: ffffc90000013a18 RBX: ffffc90000013a18 RCX: 000000000000000a [ 1.415792] RDX: 0000000000000000 RSI: ffffc90000075000 RDI: ffff888003aba000 [ 1.415792] RBP: 0000000000000000 R08: 0000000000000002 R09: ffffc900000139d4 [ 1.415792] R10: 0000000000000000 R11: ffffffff821526a0 R12: 0000000000000000 [ 1.415792] R13: ffff888003aba000 R14: 0000000000000004 R15: 0000000000000000 [ 1.415792] FS: 0000000000000000(0000) GS:ffff88809b911000(0000) knlGS:0000000000000000 [ 1.415792] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1.415792] CR2: 0000000000000000 CR3: 0000000002a32000 CR4: 00000000000006f0 [ 1.415792] Call Trace: [ 1.415792] <TASK> [ 1.415792] ? __die+0x1e/0x60 [ 1.415792] ? page_fault_oops+0x17a/0x4a0 [ 1.415792] ? search_extable+0x26/0x30 [ 1.415792] ? msix_prepare_msi_desc+0x33/0x80 [ 1.415792] ? search_module_extables+0x14/0x50 [ 1.415792] ? exc_page_fault+0x67/0x150 [ 1.415792] ? asm_exc_page_fault+0x26/0x30 [ 1.415792] ? __pfx_pci_conf1_read+0x10/0x10 [ 1.415792] ? msix_prepare_msi_desc+0x33/0x80 [ 1.415792] msix_setup_msi_descs+0xc1/0x100 [ 1.415792] __pci_enable_msix_range+0x2fa/0x5b0 [ 1.415792] pci_alloc_irq_vectors_affinity+0xa8/0x110 [ 1.415792] vp_find_vqs_msix+0x196/0x4e0 [ 1.415792] vp_find_vqs+0x3a/0x270 [ 1.415792] vp_modern_find_vqs+0x12/0x60 [ 1.415792] virtnet_find_vqs+0x1b1/0x360 [ 1.415792] virtnet_probe+0x5c2/0xf60 [ 1.415792] virtio_dev_probe+0x1b0/0x270 [ 1.415792] really_probe+0xbf/0x380 [ 1.415792] ? __pfx___driver_attach+0x10/0x10 [ 1.415792] __driver_probe_device+0x73/0x150 [ 1.415792] driver_probe_device+0x1a/0x90 [ 1.415792] __driver_attach+0xcd/0x1c0 [ 1.415792] bus_for_each_dev+0x82/0xd0 [ 1.415792] bus_add_driver+0x10d/0x240 [ 1.415792] driver_register+0x54/0x100 [ 1.415792] ? __pfx_virtio_net_driver_init+0x10/0x10 [ 1.415792] virtio_net_driver_init+0x8b/0xb0 [ 1.415792] ? __pfx_virtio_net_driver_init+0x10/0x10 [ 1.415792] do_one_initcall+0x55/0x220 [ 1.415792] kernel_init_freeable+0x199/0x2d0 [ 1.415792] ? __pfx_kernel_init+0x10/0x10 [ 1.415792] kernel_init+0x15/0x1c0 [ 1.415792] ret_from_fork+0x2f/0x50 [ 1.415792] ? __pfx_kernel_init+0x10/0x10 [ 1.415792] ret_from_fork_asm+0x1a/0x30 [ 1.415792] </TASK> [ 1.415792] Modules linked in: [ 1.415792] CR2: 0000000000000000 [ 1.415792] ---[ end trace 0000000000000000 ]--- [ 1.415792] RIP: 0010:msix_prepare_msi_desc+0x33/0x80 [ 1.415792] Code: 00 00 48 89 f0 48 8b 52 20 66 81 4e 54 01 01 c7 46 04 01 00 00 00 8b 8f 9c 03 00 00 89 4e 58 48 8b b7 a8 07 00 00 48 89 70 60 <8b> 0a 31 d2 81 e1 00 00 40 00 75 0c 0f b6 50 55 d0 ea 83 f2 01 83 [ 1.415792] RSP: 0018:ffffc90000013a00 EFLAGS: 00000202 [ 1.415792] RAX: ffffc90000013a18 RBX: ffffc90000013a18 RCX: 000000000000000a [ 1.415792] RDX: 0000000000000000 RSI: ffffc90000075000 RDI: ffff888003aba000 [ 1.415792] RBP: 0000000000000000 R08: 0000000000000002 R09: ffffc900000139d4 [ 1.415792] R10: 0000000000000000 R11: ffffffff821526a0 R12: 0000000000000000 [ 1.415792] R13: ffff888003aba000 R14: 0000000000000004 R15: 0000000000000000 [ 1.415792] FS: 0000000000000000(0000) GS:ffff88809b911000(0000) knlGS:0000000000000000 [ 1.415792] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 1.415792] CR2: 0000000000000000 CR3: 0000000002a32000 CR4: 00000000000006f0 [ 1.415792] note: swapper/0[1] exited with irqs disabled [ 1.422231] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000009 [ 1.422655] Kernel Offset: disabled + [ -e https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/312/artifact/logs/osmo_ggsn_v4_only/ggsn/_coredump/coredump ]

ERROR: Timeout while waiting for QEMU to become ready

[testenv][osmo_ggsn_v4_only] Command failed unexpectedly [testenv][osmo_ggsn_v4_only] Command: ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_cache/git', '-e', 'TESTENV_QEMU_KERNEL=https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/.linux', '-e', 'TESTENV_QEMU_SCRIPTS=https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/data/scripts/qemu', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/data/scripts/qemu:https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_cache/podman/osmo-ttcn3-hacks/ggsn_tests:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/312/artifact/logs/osmo_ggsn_v4_only/ggsn', 'testenv-ggsn_tests-osmo_ggsn_v_only-osmocom-nightly-20250327-1039-bd0bd194-0', 'sh', '-c', 'qemu_wait.sh'] [testenv][osmo_ggsn_v4_only] Returncode: 1 [testenv][osmo_ggsn_v4_only] Stopping podman container [testenv][osmo_ggsn_v4_only] + ['podman', 'kill', 'testenv-ggsn_tests-osmo_ggsn_v_only-osmocom-nightly-20250327-1039-bd0bd194-0'] testenv-ggsn_tests-osmo_ggsn_v_only-osmocom-nightly-20250327-1039-bd0bd194-0 Traceback (most recent call last): File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/./testenv.py", line 137, in <module> main() File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/./testenv.py", line 126, in main run() File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/./testenv.py", line 82, in run testenv.daemons.start(cfg) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/testenv/daemons.py", line 78, in start testenv.cmd.run(setup, cwd=cwd) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/testenv/cmd.py", line 101, in run return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/testenv/podman.py", line 149, in exec_cmd return testenv.cmd.run( ^^^^^^^^^^^^^^^^ File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/testenv/cmd.py", line 118, in run exit_error_cmd(p, "Command failed unexpectedly") File "https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/ws/_testenv/testenv/cmd.py", line 66, in exit_error_cmd raise RuntimeError("shell command related error, find details right above this python trace") RuntimeError: shell command related error, find details right above this python trace [testenv][osmo_ggsn_v4_only] Skipping clean up scripts, podman container has already stopped [testenv] feed_watchdog_loop: podman container has stopped [testenv][osmo_ggsn_v4_only] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-torvalds/312/...  Build step 'Execute shell' marked build as failure Recording test results ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error?