[ 0] capos kernel starting... [ 0] smp: BSP per-cpu active cpu=0 lapic=0 base=0xffffffff813f0300 syscall_rsp=0xffffffff813e2b40 [ 0] syscall: entry at 0xffffffff80197e1c [ 0] syscall: sqpoll-result mapping proof current_thread_blocked=true thread_owned_head=true thread_owned_head_available=true stale_sq_consumer=true proof_result=ok [ 0] x86_64: GDT + IDT + syscall initialized [ 0] Memory map (19 entries): [ 0] 0x0000000000001000 - 0x0000000000053000 5 [ 0] 0x0000000000053000 - 0x000000000009f000 0 [ 0] 0x000000000009fc00 - 0x00000000000a0000 1 [ 0] 0x00000000000f0000 - 0x0000000000100000 1 [ 0] 0x0000000000100000 - 0x0000000009f62000 0 [ 0] 0x0000000009f62000 - 0x0000000009fb6000 6 [ 0] 0x0000000009fb6000 - 0x0000000009fb7000 5 [ 0] 0x0000000009fb7000 - 0x0000000009fb8000 0 [ 0] 0x0000000009fb8000 - 0x0000000009fb9000 5 [ 0] 0x0000000009fb9000 - 0x0000000009fba000 0 [ 0] 0x0000000009fba000 - 0x0000000009fc3000 5 [ 0] 0x0000000009fc3000 - 0x000000000b3b4000 6 [ 0] 0x000000000b3b4000 - 0x000000000ff2d000 5 [ 0] 0x000000000ff2d000 - 0x000000000ff3d000 0 [ 0] 0x000000000ff3d000 - 0x000000000ffe0000 5 [ 0] 0x000000000ffe0000 - 0x0000000010000000 1 [ 0] 0x00000000fd000000 - 0x00000000fd3e8000 7 [ 0] 0x00000000feffc000 - 0x00000000ff000000 1 [ 0] 0x00000000fffc0000 - 0x0000000100000000 1 [ 0] HHDM offset: 0xffff800000000000 [ 0] frame: tracking 65341 frames (158 MB physical), bitmap at 0x53000 (8168 bytes) [ 0] heap: largest free physical run before reservation: 162184 KB (40546 frames) [ 0] heap: 98304 KB across 1 physical runs / 2 allocator regions (14 region slots free) [ 0] mem: frame allocator + heap initialized (16062 usable frames) [ 0] alloc test: [1, 2, 3, 4, 5] [ 0] paging: kernel sections: [ 0] .text: 0xffffffff80000000 - 0xffffffff808bc21f [ 0] .rodata: 0xffffffff808bd000 - 0xffffffff80a3a050 [ 0] .data: 0xffffffff80a3b000 - 0xffffffff812abaf8 [ 0] .bss: 0xffffffff812ac000 - 0xffffffff813f0ff8 [ 0] paging: preseeded kernel PML4 slot 288 for MMIO/firmware mappings [ 0] paging: kernel page tables active (PML4 at 0x6100000) [ 0] smep/smap: CR4.SMEP=1 CR4.SMAP=1 [ 0] lapic: selected mode=LapicMode::X2Apic backend=x2apic-msr [ 0] lapic: BSP x2APIC enabled id=0 [ 0] smp: Limine MP response cpus=1 bsp_lapic=0 [ 0] smp: prepared 0 AP per-cpu slot(s); scheduler handoff pending [ 0] acpi: RSDP phys=0xf5290 rev=0 oem=BOCHS root=RSDT phys=0xffe1c87 [ 0] acpi: RSDT tables=4 MADT=true MCFG=false DMAR=false IVRS=false [ 0] acpi: MADT lapic=0xfee00000 flags=0x1 enabled_lapics=1 io_apics=1 isos=5 [ 0] acpi: MADT IOAPIC[0] id=0 addr=0xfec00000 gsi_base=0 [ 0] acpi: MADT ISO[0] bus=0 source=0 gsi=2 flags=0x0000 [ 0] acpi: MADT ISO[1] bus=0 source=5 gsi=5 flags=0x000d [ 0] acpi: MADT ISO[2] bus=0 source=9 gsi=9 flags=0x000d [ 0] acpi: MADT ISO[3] bus=0 source=10 gsi=10 flags=0x000d [ 0] acpi: MADT ISO 1 additional record(s) omitted [ 0] acpi: MCFG not found [ 0] acpi: DMAR not found [ 0] acpi: IVRS not found [ 0] acpi: IOMMU policy dmar=absent ivrs=absent direct_dma=blocked bounce_buffer=required remapping_domains=unsupported userspace_dmapool=not-started virtio_net=kernel-owned-prototype [ 0] ioapic: mapped id=0 phys=0xfec00000 virt=0xffff90000000b000 gsi_base=0 redirs=24 version=0x11 madt_id=0 [ 0] ioapic: masked 24 redirection entry(s) id=0 [ 0] ioapic: route irq=0 gsi=2 vector=64 masked=true registry_source_id=16 registry_source_generation=1 registry_route_generation=2 [ 0] ioapic: route irq=0 gsi=2 vector=64 masked=true flags=0x0000 [ 0] ioapic: route irq=1 gsi=1 vector=65 masked=true registry_source_id=17 registry_source_generation=3 registry_route_generation=4 [ 0] ioapic: route irq=1 gsi=1 vector=65 masked=true flags=0x0000 [ 0] ioapic: route irq=2 gsi=2 skipped=duplicate_gsi flags=0x0000 [ 0] ioapic: route irq=3 gsi=3 vector=67 masked=true registry_source_id=19 registry_source_generation=5 registry_route_generation=6 [ 0] ioapic: route irq=3 gsi=3 vector=67 masked=true flags=0x0000 [ 0] ioapic: route irq=4 gsi=4 vector=68 masked=true registry_source_id=20 registry_source_generation=7 registry_route_generation=8 [ 0] ioapic: route irq=4 gsi=4 vector=68 masked=true flags=0x0000 [ 0] ioapic: route irq=5 gsi=5 vector=69 masked=true registry_source_id=21 registry_source_generation=9 registry_route_generation=10 [ 0] ioapic: route irq=5 gsi=5 vector=69 masked=true flags=0x000d [ 0] ioapic: route irq=6 gsi=6 vector=70 masked=true registry_source_id=22 registry_source_generation=11 registry_route_generation=12 [ 0] ioapic: route irq=6 gsi=6 vector=70 masked=true flags=0x0000 [ 0] ioapic: route irq=7 gsi=7 vector=71 masked=true registry_source_id=23 registry_source_generation=13 registry_route_generation=14 [ 0] ioapic: route irq=7 gsi=7 vector=71 masked=true flags=0x0000 [ 0] ioapic: route irq=8 gsi=8 vector=72 masked=true registry_source_id=24 registry_source_generation=15 registry_route_generation=16 [ 0] ioapic: route irq=8 gsi=8 vector=72 masked=true flags=0x0000 [ 0] ioapic: route irq=9 gsi=9 vector=73 masked=true registry_source_id=25 registry_source_generation=17 registry_route_generation=18 [ 0] ioapic: route irq=9 gsi=9 vector=73 masked=true flags=0x000d [ 0] ioapic: route irq=10 gsi=10 vector=74 masked=true registry_source_id=26 registry_source_generation=19 registry_route_generation=20 [ 0] ioapic: route irq=10 gsi=10 vector=74 masked=true flags=0x000d [ 0] ioapic: route irq=11 gsi=11 vector=75 masked=true registry_source_id=27 registry_source_generation=21 registry_route_generation=22 [ 0] ioapic: route irq=11 gsi=11 vector=75 masked=true flags=0x000d [ 0] ioapic: route irq=12 gsi=12 vector=76 masked=true registry_source_id=28 registry_source_generation=23 registry_route_generation=24 [ 0] ioapic: route irq=12 gsi=12 vector=76 masked=true flags=0x0000 [ 0] ioapic: route irq=13 gsi=13 vector=77 masked=true registry_source_id=29 registry_source_generation=25 registry_route_generation=26 [ 0] ioapic: route irq=13 gsi=13 vector=77 masked=true flags=0x0000 [ 0] ioapic: route irq=14 gsi=14 vector=78 masked=true registry_source_id=30 registry_source_generation=27 registry_route_generation=28 [ 0] ioapic: route irq=14 gsi=14 vector=78 masked=true flags=0x0000 [ 0] ioapic: route irq=15 gsi=15 vector=79 masked=true registry_source_id=31 registry_source_generation=29 registry_route_generation=30 [ 0] ioapic: route irq=15 gsi=15 vector=79 masked=true flags=0x0000 [ 0] ioapic: routes configured 15 legacy IRQ(s) masked=true [ 0] ioapic: route irq=7 gsi=7 vector=71 masked=false id=0 flags=0x0000 [ 0] ioapic: route irq=7 gsi=7 vector=71 masked=true id=0 flags=0x0000 [ 0] interrupt: route dispatch proof source=legacy-ioapic owner=kernel-ioapic-proof irq=7 gsi=7 source_id=23 source_generation=13 route_generation=14 lapic_vector=71 route_state=claimed-masked masked_delivery=masked masked_dispatch_result=state-mismatch wrong_owner_unmask_result=owner-mismatch unmask_result=ok dispatch_result=delivered dispatch_count_before=0 dispatch_count_after=1 dispatch_count_changed=true mask_result=ok final_masked_delivery=masked final_route_state=claimed-masked proof_result=ok [ 0] pci: ECAM unavailable (no MCFG allocations) [ 0] pci: ECAM enumerated 0 function(s) [ 0] pci: config backend=ecam enumerated 0 function(s) [ 0] pci: dma-attachment policy total_dma_capable=0 retained_printed=0 omitted=0 iommu_attached=0 iommu_covered=0 bounce_buffer_required=0 prototype_bounce_buffer_only=0 direct_dma_trusted_domains=0 remapping_domains=unsupported userspace_dmapool=not-started [ 0] pci: dma-domain policy proof total_dma_capable=0 retained_printed=0 omitted=0 iommu_attached=0 iommu_covered=0 prototype_bounce_buffer_only=0 blocked_direct_dma_devices=0 claimed_device_domain_owner=future-device-manager domain_granularity=per-device-or-trusted-sharing-group trusted_sharing_groups=future direct_dma=blocked bounce_buffer=required exported_device_addresses=iova-only host_physical_user_visible=0 direct_dma_trusted_domains=0 claimed_device_domains_ready=0 remapping_tables=not-programmed remapping_tables_programmed=0 remapping_domains=not-started userspace_dmapool=not-started userspace_devicemmio=not-started userspace_interrupt=not-started prototype_devices=kernel-owned-bounce-buffer-only metadata_status=absent-blocked unsafe_metadata_policy=fail-closed domain_activation=not-started policy=blocked-direct-dma proof_result=ok [ 0] pci: iommu-remapping table-scaffold proof backend=intel-qemu backend_state=disabled disabled_reason=no-dma-capable-pci-function representable=true drhd=absent drhd_index=0 drhd_segment=0 drhd_include_all=false drhd_register_base_user_visible=0 source_segment=0 bdf=00:00.0 source_id=0x0000 domain_id=1 address_width=qemu-intel-aw39 address_width_bits=39 second_level_levels=3 root_entry=representable-disabled context_entry=representable-disabled second_level_root=placeholder root_table_pointer=not-written context_entry_programming=not-written invalidation_registers=not-written fault_registers=not-written protected_memory_registers=not-written invalidation_queue=not-written remapping_tables=not-programmed hardware_programming=not-attempted direct_dma=blocked iova_export=disabled-future-only host_physical_user_visible=0 proof_result=ok [ 0] pci: iommu-remapping mmio-status proof backend=intel-qemu backend_state=disabled disabled_reason=no-dma-capable-pci-function drhd=absent drhd_index=0 drhd_segment=0 drhd_include_all=false drhd_register_base_user_visible=0 chosen_drhd=absent source_segment=0 bdf=00:00.0 source_id=0x0000 address_width=qemu-intel-aw39 address_width_bits=39 mmio_window=not-mapped mmio_read=not-attempted read_result=no-drhd capability_read=unavailable status_read=unavailable fault_status_read=unavailable version_major=0 version_minor=0 cap_mgaw_bits=0 cap_sagaw_mask=0x00 selected_address_width_status=not-read root_table_readiness=not-programmed translation_status=not-read root_table_status=not-read invalidation_readiness=not-installed fault_status_readiness=not-read fault_status=unavailable remapping_tables=not-programmed direct_dma=blocked fallback_policy=kernel-owned-bounce-buffer-only hostile_hardware_isolation=not-claimed fail_closed_reason=no-dma-capable-pci-function proof_result=ok [ 0] pci: nvme metadata backend=legacy-io functions=0 printed=0 print_limit=4 class=01 subclass=08 expected_qemu_prog_if=02 proof_scope=metadata-only-pci-discovery proof_result=not-found [ 0] pci: enumerated 6 function(s) [ 0] pci: virtio-net device not found [ 0] pci: virtio-blk device not found [ 0] virtio-rng: classification=qemu-only-proof-fixture userspace_capability=none production_driver=no role=ddf-metadata-and-iommu-remapping-proof-vehicle entropy_source=rdrand-separate [ 0] pci: virtio-rng device not found [ 0] pci: virtio-9p device not found [ 0] iommu-remapping: fallback proof shape=no-intel-iommu-drhd-for-selected-function fallback_policy=kernel-owned-bounce-buffer-only remapping_tables=not-programmed host_physical_user_visible=0 iova_export=disabled-this-slice hostile_hardware_isolation=not-claimed proof_result=ok [ 0] cap: capability table initialized (3 capabilities: console=0, frame_alloc=1, endpoint=2) [ 0] hardware-audit: snapshot truncation contract proof proof_scope=local-ring-no-live-audit-mutation empty_status=available-records-exhausted empty_returned=0 no_records_status=no-records-requested no_records_returned=0 request_status=request-limited request_returned=2 request_first_sequence=3 available_status=available-records-exhausted available_returned=4 available_available=4 snapshot_status=snapshot-limit-limited snapshot_returned=16 snapshot_first_returned=5 snapshot_last_returned=20 proof_result=ok [ 0] hardware-audit: snapshot cursor contract proof proof_scope=local-ring-no-live-audit-mutation cursor_clamp_start=6 cursor_clamp_first_sequence=7 cursor_clamp_next_sequence=71 cursor_clamp_available=64 cursor_clamp_returned=16 cursor_clamp_first_returned=7 cursor_clamp_last_returned=22 cursor_clamp_status=snapshot-limit-limited past_end_start=78 past_end_first_sequence=71 past_end_next_sequence=71 past_end_available=0 past_end_returned=0 past_end_status=available-records-exhausted proof_result=ok [ 0] hardware-audit: drain reply-failure retry proof proof_scope=local-ring-no-live-audit-mutation failure_injected=reply-scratch retry_expected_sequence=1 retry_first_sequence=1 retry_last_sequence=2 retry_next_sequence=3 proof_result=ok [ 0] nvme: validator selftest model=B scan=on-notify backend=bounce-buffer owner=nvme-storage owner_generation=7 window_base=0x40000000 window_len=0x10000 proof_scope=bounded-hostile-scan-selftest [ 0] nvme: validator accept kind=queue-arm owner=nvme-storage scanned_items=2 doorbell_written=true scan_before_doorbell=ok result=accept proof_result=ok [ 0] nvme: validator accept kind=sq-tail-doorbell owner=nvme-storage scanned_items=2 doorbell_written=true scan_before_doorbell=ok result=accept proof_result=ok [ 0] nvme: validator reject case=out-of-window kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator reject case=host-physical kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator reject case=cross-owner-alias kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator reject case=region-overrun kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator reject case=unaligned kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator reject case=deep-prp-chain kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator reject case=stale-generation kind=sq-tail-doorbell owner=nvme-storage doorbell_written=false waiter_wake=none result=reject proof_result=ok [ 0] nvme: validator completion submission_validated=true generation_match=true waiter_wake=signal result=ok proof_result=ok [ 0] nvme: validator completion submission_validated=false generation_match=false waiter_wake=none result=ok proof_result=ok [ 0] nvme: validator completion submission_validated=true generation_match=false waiter_wake=none result=ok proof_result=ok [ 0] nvme: validator perf scan=on-notify cost=O(descriptors-per-doorbell) accepts=2 rejects=7 max_scanned_items=2 steady_state_writes=uninstrumented proof_result=ok [ 0] nvme: validator summary model=B accepts=2 rejects=7 doorbell_writes_on_reject=0 waiter_wakes_on_reject=0 hostile_cases=out-of-window,host-physical,cross-owner-alias,region-overrun,unaligned,deep-prp-chain,stale-generation backend=bounce-buffer proof_scope=bounded-hostile-scan-selftest live_doorbell_wiring=nvme-userspace-bind-and-controller-bringup proof_result=ok [ 0] smp: prepared idle kernel stack cpu=0 top=0xffff800006130000 frames=32 [ 0] smp: prepared idle kernel stack cpu=1 top=0xffff800006150000 frames=32 [ 0] smp: prepared idle kernel stack cpu=2 top=0xffff800006170000 frames=32 [ 0] smp: prepared idle kernel stack cpu=3 top=0xffff800006190000 frames=32 [ 0] sched: CPL0 idle context cpu=0 entry=0xffffffff80610550 kernel_rsp=0xffff800006130000 cs=0x8 ss=0x10 [ 0] sched: CPL0 idle context cpu=1 entry=0xffffffff80610550 kernel_rsp=0xffff800006150000 cs=0x8 ss=0x10 [ 0] sched: CPL0 idle context cpu=2 entry=0xffffffff80610550 kernel_rsp=0xffff800006170000 cs=0x8 ss=0x10 [ 0] sched: CPL0 idle context cpu=3 entry=0xffffffff80610550 kernel_rsp=0xffff800006190000 cs=0x8 ss=0x10 [ 0] sched: prepared 4 CPL0 idle-thread context(s) [ 0] sched: idle process 1 cpu_slot=0 [ 0] pic: remapped to vectors 32-47, all IRQs masked [ 0] lapic: BSP periodic timer vector=48 initial_count=631016 divide=16 calibrated_hz=100 clocksource=tsc-calibrated calibration_ns=10000150 tsc_delta=28140972 [ 0] clockevent: proof backend=lapic oneshot=programmed deadline_ns=17701061 delta_ns=5000000 count=315508 restored_periodic=true tick_suppression=disabled auto_nohz=disabled full_nohz=disabled [ 0] clockevent-fire: backend=lapic fired=true requested_delta_ns=5000000 observed_delta_ns=5114291 count=315508 count_after_program=313452 periodic_initial_count=631016 within_tolerance=true restored_periodic=true periodic_live=true [ 0] clockevent-floor: clocksource=tsc real_ns=5000003 monotonic_delta_ns=5000089 tick_ns=10000000 floored=false subtick_tracked=true [ 0] smp: no AP slots prepared; AP bootstrap skipped [ 0] manifest: loaded 342160 bytes from boot module [ 0] manifest: initConfig handoff, 3 binaries [ 0] manifest: logLevel=debug [ 0] cpu-isolation: declared-pools source=kernel-default count=2 default_pool_id=0 nondefault_pool_id=1 nondefault_pool_cpu_mask=0x2 [ 0] cpu-isolation: pool-grants source=kernel-default count=1 default_bound_account_id=7 default_bound_pool_id=1 [ 0] cpu-isolation: grant-minter-allowlist source=empty count=0 [ 0] dma: backend selection dma_backend=bounce-buffer dma_backend_override=absent probe_verified_usable_iommu=false [ 0] cloudboot-evidence: dma-backend bounce_buffer [ 0] pci: enumeration source=legacy-io validated_mcfg_allocations=0 [ 0] cloudboot-evidence: enumeration-source legacy-io [ 0] cloudboot-evidence: device-class 0x01 [ 0] cloudboot-evidence: device-class 0x02 [ 0] cloudboot-evidence: device-class 0x03 [ 0] cloudboot-evidence: device-class 0x06 [ 0] pci-inventory: 0000:00:00.0 vendor=0x8086 device=0x1237 class=06 subclass=00 prog_if=00 revision=02 header=00 interrupt_pin=0 interrupt_line=0 [ 0] pci-inventory: capability-walk outcome=no-capability-list caps=0 [ 0] cloudboot-evidence: device-inventory 0000.00.00.0-8086.1237-06.00.00-rev.02-hdr.00-irq.0.0 [ 0] cloudboot-evidence: capability-walk 0000.00.00.0-8086.1237-walk.no-capability-list-caps.0 [ 0] pci-inventory: 0000:00:01.0 vendor=0x8086 device=0x7000 class=06 subclass=01 prog_if=00 revision=00 header=80 interrupt_pin=0 interrupt_line=0 [ 0] pci-inventory: capability-walk outcome=no-capability-list caps=0 [ 0] cloudboot-evidence: device-inventory 0000.00.01.0-8086.7000-06.01.00-rev.00-hdr.80-irq.0.0 [ 0] cloudboot-evidence: capability-walk 0000.00.01.0-8086.7000-walk.no-capability-list-caps.0 [ 0] pci-inventory: 0000:00:01.1 vendor=0x8086 device=0x7010 class=01 subclass=01 prog_if=80 revision=00 header=00 interrupt_pin=0 interrupt_line=0 [ 0] pci-inventory: BAR4 io base=0xc040 prefetchable=false [ 0] pci-inventory: capability-walk outcome=no-capability-list caps=0 [ 0] cloudboot-evidence: device-inventory 0000.00.01.1-8086.7010-01.01.80-rev.00-hdr.00-irq.0.0-b4.io.base.c040 [ 0] cloudboot-evidence: capability-walk 0000.00.01.1-8086.7010-walk.no-capability-list-caps.0 [ 0] pci-inventory: 0000:00:01.3 vendor=0x8086 device=0x7113 class=06 subclass=80 prog_if=00 revision=03 header=00 interrupt_pin=1 interrupt_line=9 [ 0] pci-inventory: capability-walk outcome=no-capability-list caps=0 [ 0] cloudboot-evidence: device-inventory 0000.00.01.3-8086.7113-06.80.00-rev.03-hdr.00-irq.1.9 [ 0] cloudboot-evidence: capability-walk 0000.00.01.3-8086.7113-walk.no-capability-list-caps.0 [ 0] pci-inventory: 0000:00:02.0 vendor=0x1234 device=0x1111 class=03 subclass=00 prog_if=00 revision=02 header=00 interrupt_pin=0 interrupt_line=0 [ 0] pci-inventory: BAR0 mem32 base=0xfd000000 prefetchable=true [ 0] pci-inventory: BAR2 mem32 base=0xfebb0000 prefetchable=false [ 0] pci-inventory: capability-walk outcome=no-capability-list caps=0 [ 0] cloudboot-evidence: device-inventory 0000.00.02.0-1234.1111-03.00.00-rev.02-hdr.00-irq.0.0-b0.m32p.base.fd000000-b2.m32.base.febb0000 [ 0] cloudboot-evidence: capability-walk 0000.00.02.0-1234.1111-walk.no-capability-list-caps.0 [ 0] pci-inventory: 0000:00:03.0 vendor=0x8086 device=0x100e class=02 subclass=00 prog_if=00 revision=03 header=00 interrupt_pin=1 interrupt_line=11 [ 0] pci-inventory: BAR0 mem32 base=0xfeb80000 prefetchable=false [ 0] pci-inventory: BAR1 io base=0xc000 prefetchable=false [ 0] pci-inventory: capability-walk outcome=no-capability-list caps=0 [ 0] cloudboot-evidence: device-inventory 0000.00.03.0-8086.100e-02.00.00-rev.03-hdr.00-irq.1.11-b0.m32.base.feb80000-b1.io.base.c000 [ 0] cloudboot-evidence: capability-walk 0000.00.03.0-8086.100e-walk.no-capability-list-caps.0 [ 0] manifest: booting init from initConfig only [ 0] manifest: init 'init' is the reserved embedded identity; loading PID 1 from kernel INIT_ELF (740000 bytes) [ 0] binary 'init': 740000 bytes, entry 0x218af0, 3 segments [ 0] cap: service 'init' granted 'console' (source=kernel:console) -> cap_id=0 [ 0] cap: service 'init' granted 'boot' (source=kernel:boot_package) -> cap_id=1 [ 0] cap: service 'init' granted 'spawner' (source=kernel:process_spawner) -> cap_id=2 [ 0] heap: after boot caps: used=21483 KiB free=76820 KiB [ 0] proc: created process 2 for service 'init' (binary='init', 3 caps) [ 0] sched: added process 2 [ 0] sched: BSP cpu=0 starting thread 2:1 [init] Hello from capos-rt init skeleton. [init] BootPackage manifest graph validation ok. [ 6] proc: spawned process 3 for request 'ring-nop' (binary='ring-nop', 4 caps) [ 6] proc: process 3 session context profile='system' for request 'ring-nop' [ 6] sched: added process 3 [ 7] sched: switch 2:1 -> 3:1 [ 8] pid=3 invalid submission err=-1 opcode=5 cap_id_bucket=0 cause=structural [ 8] pid=3 invalid submission err=-1 opcode=5 cap_id_bucket=0 cause=structural [ 9] pid=3 invalid submission err=-1 opcode=5 cap_id_bucket=0 cause=structural [ 9] pid=3 invalid submission err=-2 opcode=0 cap_id_bucket=0 cause=structural [ 10] pid=3 invalid submission err=-3 opcode=0 cap_id_bucket=0 cause=structural [ 10] ring: failed to serialize CapException into result buffer: Failed: result buffer too small [ 11] pid=3 invalid submission err=-2 opcode=0 cap_id_bucket=0 cause=structural [ 11] pid=3 invalid submission err=-4 opcode=0 cap_id_bucket=4294967295 cause=cap-not-found [ 12] pid=3 invalid submission err=-4 opcode=1 cap_id_bucket=0 cause=cap-invoke-error [ring-nop] CAP_OP_NOP smoke ok. [ring-nop] cold cap_enter cycles: first_nop_round_trip=316695 [ 14] sched: switch 3:1 -> 2:1 [ 14] proc: spawned process 4 for request 'thread-lifecycle' (binary='thread-lifecycle', 8 caps) [ 14] proc: process 4 session context profile='system' for request 'thread-lifecycle' [ 14] sched: added process 4 [ 15] sched: switch 2:1 -> 4:1 [ 16] sched: switch 4:1 -> 4:2 [ 18] sched: switch 4:2 -> 4:1 [ 18] sched: blocked 4:1 -> 4:2 [ 19] sched: switch 4:2 -> 4:1 [ 19] sched: blocked 4:1 -> 3:1 [ring-nop] measure avg cycles: nop=33479 null_empty=36999 null_small=5123140 [ 20] sched: switch 3:1 -> 4:2 [ 20] sched: switch to 4:1 [ 21] sched: off-stack thread stack release pid=4 tid=2 reason=thread-exit proof_result=ok [ 21] sched: switch 4:1 -> 4:3 [ 24] sched: switch 4:3 -> 3:1 [ 25] sched: switch 3:1 -> 4:1 [ 26] sched: switch 4:1 -> 4:3 [ 26] sched: switch to 3:1 [ 27] sched: off-stack thread stack release pid=4 tid=3 reason=thread-exit proof_result=ok [ 27] sched: switch 3:1 -> 4:1 [ 28] sched: switch 4:1 -> 4:4 [ 30] sched: switch 4:4 -> 4:1 [ 31] sched: switch 4:1 -> 4:4 [ 32] sched: switch 4:4 -> 3:1 [ 33] sched: switch 3:1 -> 4:1 [ 33] sched: blocked 4:1 -> 4:4 [ 34] sched: switch 4:4 -> 4:1 [ 35] sched: switch 4:1 -> 4:4 [ 35] sched: blocked 4:4 -> 3:1 [ 36] sched: switch 3:1 -> 4:1 [ 37] sched: switch 4:1 -> 4:4 [ 37] sched: switch to 3:1 [ 38] sched: off-stack thread stack release pid=4 tid=4 reason=thread-exit proof_result=ok [ 38] sched: switch 3:1 -> 4:1 [thread-lifecycle] park handoff wake retry ok. [ 40] sched: switch 4:1 -> 4:6 [ 40] sched: blocked 4:6 -> 4:5 [ 40] sched: blocked 4:5 -> 2:1 [ 40] proc: spawned process 5 for request 'unprivileged-stranger' (binary='unprivileged-stranger', 0 caps) [ 40] proc: process 5 session context profile='system' for request 'unprivileged-stranger' [ 40] sched: added process 5 [ 41] sched: switch 2:1 -> 5:1 [ 41] syscall: unknown syscall 0 [ 41] pid=5 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 42] pid=5 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 42] pid=5 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 43] sched: switch 5:1 -> 3:1 [ 44] sched: switch 3:1 -> 4:1 [ 44] sched: blocked 4:1 -> 3:1 [ 45] sched: switch 3:1 -> 5:1 [ 45] sched: process_cpu_accounting pid=5 runtime_ns=22383558 context_switches=3 [ 45] proc: process 5 exited with code 0 [ 45] sched: switch to 3:1 [ 46] sched: off-stack process stack release pid=5 stacks=1 reason=deferred-process-drop proof_result=ok [ 46] sched: switch 3:1 -> 4:1 [ 48] sched: switch 4:1 -> 4:5 [ 48] sched: switch to 4:6 [ 48] sched: off-stack thread stack release pid=4 tid=5 reason=thread-exit proof_result=ok [ 48] sched: switch to 4:1 [ 49] sched: off-stack thread stack release pid=4 tid=6 reason=thread-exit proof_result=ok [ 50] sched: switch 4:1 -> 4:7 [ 52] sched: switch 4:7 -> 3:1 [ring-nop] park bench avg cycles: compact_wait_miss=36198 generic_wait_miss=4712151 compact_wake_empty=34554 generic_wake_empty=8396561 [ 53] sched: switch 3:1 -> 4:1 [ 54] sched: switch 4:1 -> 4:8 [ 54] sched: blocked 4:8 -> 4:7 [ 54] sched: blocked 4:7 -> 4:1 [ 54] sched: blocked 4:1 -> 3:1 [ 57] sched: switch 3:1 -> 4:1 [ 58] sched: switch 4:1 -> 4:8 [ 58] sched: switch to 4:7 [ 58] sched: off-stack thread stack release pid=4 tid=8 reason=thread-exit proof_result=ok [ 58] sched: switch to 4:1 [ 59] sched: off-stack thread stack release pid=4 tid=7 reason=thread-exit proof_result=ok [ 59] sched: switch 4:1 -> 2:1 [init] Generic manifest child wait ok: unprivileged-stranger [ 60] sched: blocked 2:1 -> 4:1 [thread-lifecycle] park multi-waiter unmap reuse cleanup ok. [ 61] sched: switch 4:1 -> 3:1 [ 62] sched: switch 3:1 -> 4:1 [ 63] sched: switch 4:1 -> 4:9 [ 63] sched: blocked 4:9 -> 3:1 [ring-nop] sqe throughput workload: sqes=4096 [ 64] sched: switch 3:1 -> 4:1 [ 64] sched: blocked 4:1 -> 3:1 [ 64] sched: process_cpu_accounting pid=3 runtime_ns=204275035 context_switches=23 [ 64] proc: process 3 exited with code 0 [ 64] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-exit [ 64] sched: switch to 1:1 [ 65] sched: off-stack process stack release pid=3 stacks=1 reason=deferred-process-drop proof_result=ok [ 65] sched: cpl0-idle cpu=0 starting thread 4:1 [ 67] sched: switch 4:1 -> 4:9 [ 67] sched: switch to 4:1 [ 68] sched: off-stack thread stack release pid=4 tid=9 reason=thread-exit proof_result=ok [ 68] sched: switch 4:1 -> 4:10 [ 68] sched: blocked 4:10 -> 4:1 [ 69] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 69] sched: blocked 4:1 -> 1:1 [ 69] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=1 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 70] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=2 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 71] sched: cpl0-idle cpu=0 starting thread 4:1 [ 72] sched: switch 4:1 -> 4:10 [ 72] sched: switch to 4:1 [thread-lifecycle] park unmap/decommit/memoryobject cleanup ok. [ 73] sched: off-stack thread stack release pid=4 tid=10 reason=thread-exit proof_result=ok [ 74] sched: switch 4:1 -> 4:11 [ 74] sched: blocked 4:11 -> 4:1 [ 74] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 74] sched: blocked 4:1 -> 1:1 [ 75] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=3 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 76] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=4 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 77] sched: cpl0-idle cpu=0 starting thread 4:1 [ 78] sched: switch 4:1 -> 4:11 [ 81] sched: switch 4:11 -> 4:1 [ 82] sched: switch 4:1 -> 4:11 [ 82] sched: blocked 4:11 -> 4:1 [ 82] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 82] sched: blocked 4:1 -> 1:1 [ 83] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=5 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 84] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=6 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 85] sched: cpl0-idle cpu=0 starting thread 4:1 [ 86] sched: switch 4:1 -> 4:11 [ 88] sched: switch 4:11 -> 4:1 [ 89] sched: switch 4:1 -> 4:11 [ 89] sched: blocked 4:11 -> 4:1 [ 89] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 89] sched: blocked 4:1 -> 1:1 [ 90] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=7 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 91] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=8 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 92] sched: cpl0-idle cpu=0 starting thread 4:1 [ 93] sched: switch 4:1 -> 4:11 [ 95] sched: switch 4:11 -> 4:1 [ 96] sched: switch 4:1 -> 4:11 [ 96] sched: blocked 4:11 -> 4:1 [ 96] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 96] sched: blocked 4:1 -> 1:1 [ 99] sched: cpl0-idle cpu=0 starting thread 4:1 [ 100] sched: switch 4:1 -> 4:11 [ 102] sched: switch 4:11 -> 4:1 [ 103] sched: switch 4:1 -> 4:11 [ 103] sched: blocked 4:11 -> 4:1 [ 103] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 103] sched: blocked 4:1 -> 1:1 [ 106] sched: cpl0-idle cpu=0 starting thread 4:1 [ 107] sched: switch 4:1 -> 4:11 [ 109] sched: switch 4:11 -> 4:1 [ 110] sched: switch 4:1 -> 4:11 [ 110] sched: blocked 4:11 -> 4:1 [ 110] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 110] sched: blocked 4:1 -> 1:1 [ 113] sched: cpl0-idle cpu=0 starting thread 4:1 [ 114] sched: switch 4:1 -> 4:11 [ 114] sched: switch to 4:1 [thread-lifecycle] park path avg cycles: failed_wait=3029417 empty_wake=240011 wait_to_block=87530372 wake_to_runnable=406009 wake_to_resume=25657507 [ 115] sched: off-stack thread stack release pid=4 tid=11 reason=thread-exit proof_result=ok [ 116] sched: switch 4:1 -> 4:12 [ 116] sched: blocked 4:12 -> 4:1 [ 116] sched: blocked 4:1 -> 4:12 [ 117] sched: blocked 4:12 -> 4:1 [ 117] sched: blocked 4:1 -> 4:12 [ 117] sched: blocked 4:12 -> 4:1 [ 117] sched: blocked 4:1 -> 4:12 [ 118] sched: blocked 4:12 -> 4:1 [ 118] sched: blocked 4:1 -> 4:12 [ 118] sched: blocked 4:12 -> 4:1 [ 119] sched: blocked 4:1 -> 4:12 [ 119] sched: blocked 4:12 -> 4:1 [ 119] sched: blocked 4:1 -> 4:12 [ 119] sched: blocked 4:12 -> 4:1 [ 120] sched: blocked 4:1 -> 4:12 [ 120] sched: blocked 4:12 -> 4:1 [ 120] sched: blocked 4:1 -> 4:12 [ 120] sched: blocked 4:12 -> 4:1 [ 121] sched: blocked 4:1 -> 4:12 [ 121] sched: blocked 4:12 -> 4:1 [ 121] sched: blocked 4:1 -> 4:12 [ 122] sched: blocked 4:12 -> 4:1 [ 122] sched: blocked 4:1 -> 4:12 [ 122] sched: blocked 4:12 -> 4:1 [ 122] sched: blocked 4:1 -> 4:12 [ 123] sched: blocked 4:12 -> 4:1 [ 123] sched: blocked 4:1 -> 4:12 [ 123] sched: blocked 4:12 -> 4:1 [ 124] sched: blocked 4:1 -> 4:12 [ 124] sched: blocked 4:12 -> 4:1 [ 124] sched: blocked 4:1 -> 4:12 [ 124] sched: blocked 4:12 -> 4:1 [ 125] sched: blocked 4:1 -> 4:12 [ 125] sched: blocked 4:12 -> 4:1 [ 125] sched: blocked 4:1 -> 4:12 [ 126] sched: blocked 4:12 -> 4:1 [ 126] sched: blocked 4:1 -> 4:12 [ 126] sched: blocked 4:12 -> 4:1 [ 126] sched: blocked 4:1 -> 4:12 [ 127] sched: blocked 4:12 -> 4:1 [ 127] sched: blocked 4:1 -> 4:12 [ 127] sched: blocked 4:12 -> 4:1 [ 127] sched: blocked 4:1 -> 4:12 [ 128] sched: blocked 4:12 -> 4:1 [ 128] sched: blocked 4:1 -> 4:12 [ 128] sched: blocked 4:12 -> 4:1 [ 129] sched: blocked 4:1 -> 4:12 [ 129] sched: blocked 4:12 -> 4:1 [ 129] sched: blocked 4:1 -> 4:12 [ 129] sched: blocked 4:12 -> 4:1 [ 130] sched: blocked 4:1 -> 4:12 [ 130] sched: blocked 4:12 -> 4:1 [ 130] sched: blocked 4:1 -> 4:12 [ 131] sched: blocked 4:12 -> 4:1 [ 131] sched: blocked 4:1 -> 4:12 [ 131] sched: blocked 4:12 -> 4:1 [ 131] sched: blocked 4:1 -> 4:12 [ 132] sched: blocked 4:12 -> 4:1 [ 132] sched: blocked 4:1 -> 4:12 [ 132] sched: blocked 4:12 -> 4:1 [ 132] sched: blocked 4:1 -> 4:12 [ 133] sched: blocked 4:12 -> 4:1 [ 133] sched: blocked 4:1 -> 4:12 [ 133] sched: blocked 4:12 -> 4:1 [ 134] sched: blocked 4:1 -> 4:12 [ 134] sched: blocked 4:12 -> 4:1 [ 134] sched: blocked 4:1 -> 4:12 [ 134] sched: blocked 4:12 -> 4:1 [ 135] sched: blocked 4:1 -> 4:12 [ 135] sched: blocked 4:12 -> 4:1 [ 135] sched: blocked 4:1 -> 4:12 [ 136] sched: blocked 4:12 -> 4:1 [ 136] sched: blocked 4:1 -> 4:12 [ 136] sched: blocked 4:12 -> 4:1 [ 136] sched: blocked 4:1 -> 4:12 [ 137] sched: blocked 4:12 -> 4:1 [ 137] sched: blocked 4:1 -> 4:12 [ 137] sched: blocked 4:12 -> 4:1 [ 137] sched: blocked 4:1 -> 4:12 [ 138] sched: blocked 4:12 -> 4:1 [ 138] sched: blocked 4:1 -> 4:12 [ 138] sched: blocked 4:12 -> 4:1 [ 139] sched: blocked 4:1 -> 4:12 [ 139] sched: blocked 4:12 -> 4:1 [ 139] sched: blocked 4:1 -> 4:12 [ 139] sched: blocked 4:12 -> 4:1 [ 140] sched: blocked 4:1 -> 4:12 [ 140] sched: blocked 4:12 -> 4:1 [ 140] sched: blocked 4:1 -> 4:12 [ 141] sched: blocked 4:12 -> 4:1 [ 141] sched: blocked 4:1 -> 4:12 [ 141] sched: blocked 4:12 -> 4:1 [ 141] sched: blocked 4:1 -> 4:12 [ 142] sched: blocked 4:12 -> 4:1 [ 142] sched: blocked 4:1 -> 4:12 [ 142] sched: blocked 4:12 -> 4:1 [ 142] sched: blocked 4:1 -> 4:12 [ 143] sched: blocked 4:12 -> 4:1 [ 143] sched: blocked 4:1 -> 4:12 [ 143] sched: blocked 4:12 -> 4:1 [ 144] sched: blocked 4:1 -> 4:12 [ 144] sched: blocked 4:12 -> 4:1 [ 144] sched: blocked 4:1 -> 4:12 [ 144] sched: blocked 4:12 -> 4:1 [ 145] sched: blocked 4:1 -> 4:12 [ 145] sched: blocked 4:12 -> 4:1 [ 145] sched: blocked 4:1 -> 4:12 [ 146] sched: switch 4:12 -> 4:1 [ 146] sched: blocked 4:1 -> 4:12 [ 146] sched: blocked 4:12 -> 4:1 [ 146] sched: blocked 4:1 -> 4:12 [ 147] sched: blocked 4:12 -> 4:1 [ 147] sched: blocked 4:1 -> 4:12 [ 147] sched: blocked 4:12 -> 4:1 [ 147] sched: blocked 4:1 -> 4:12 [ 148] sched: blocked 4:12 -> 4:1 [ 148] sched: blocked 4:1 -> 4:12 [ 148] sched: blocked 4:12 -> 4:1 [ 149] sched: blocked 4:1 -> 4:12 [ 149] sched: blocked 4:12 -> 4:1 [ 149] sched: blocked 4:1 -> 4:12 [ 149] sched: blocked 4:12 -> 4:1 [ 150] sched: blocked 4:1 -> 4:12 [ 150] sched: blocked 4:12 -> 4:1 [ 150] sched: blocked 4:1 -> 4:12 [ 150] sched: blocked 4:12 -> 4:1 [ 151] sched: blocked 4:1 -> 4:12 [ 151] sched: blocked 4:12 -> 4:1 [ 151] sched: blocked 4:1 -> 4:12 [ 152] sched: blocked 4:12 -> 4:1 [ 152] sched: blocked 4:1 -> 4:12 [ 152] sched: blocked 4:12 -> 4:1 [ 152] sched: blocked 4:1 -> 4:12 [ 153] sched: blocked 4:12 -> 4:1 [ 153] sched: blocked 4:1 -> 4:12 [ 153] sched: blocked 4:12 -> 4:1 [ 154] sched: blocked 4:1 -> 4:12 [ 154] sched: blocked 4:12 -> 4:1 [ 154] sched: blocked 4:1 -> 4:12 [ 154] sched: blocked 4:12 -> 4:1 [ 155] sched: blocked 4:1 -> 4:12 [ 155] sched: blocked 4:12 -> 4:1 [ 155] sched: blocked 4:1 -> 4:12 [ 155] sched: blocked 4:12 -> 4:1 [ 156] sched: blocked 4:1 -> 4:12 [ 156] sched: switch to 4:1 [thread-lifecycle] endpoint CALL-to-receiver avg cycles: 7840619 [ 157] sched: off-stack thread stack release pid=4 tid=12 reason=thread-exit proof_result=ok [thread-lifecycle] endpoint matched avg cycles: direct=7790392 ordinary=7890846 [thread-lifecycle] thread lifecycle checkpoint ok. [ 158] sched: process_cpu_accounting pid=4 runtime_ns=1025312286 context_switches=284 [ 158] proc: process 4 exited with code 0 [ 158] sched: switch to 2:1 [ 159] sched: off-stack process stack release pid=4 stacks=1 reason=deferred-process-drop proof_result=ok [init] Generic manifest child wait ok: thread-lifecycle [init] Generic manifest child wait ok: ring-nop [init] Generic manifest spawn loop ok. [ 160] proc: spawned process 6 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 160] proc: process 6 session context profile='system' for request 'cap-slot-leak' [ 160] sched: added process 6 [ 161] sched: switch 2:1 -> 6:1 [ 161] syscall: unknown syscall 0 [ 161] pid=6 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 162] pid=6 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 162] pid=6 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 163] sched: process_cpu_accounting pid=6 runtime_ns=20588998 context_switches=3 [ 163] proc: process 6 exited with code 0 [ 163] sched: switch to 2:1 [ 164] sched: off-stack process stack release pid=6 stacks=1 reason=deferred-process-drop proof_result=ok [ 165] proc: spawned process 7 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 165] proc: process 7 session context profile='system' for request 'cap-slot-leak' [ 165] sched: added process 7 [ 166] sched: switch 2:1 -> 7:1 [ 166] syscall: unknown syscall 0 [ 167] pid=7 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 167] pid=7 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 168] pid=7 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 168] sched: process_cpu_accounting pid=7 runtime_ns=20558000 context_switches=3 [ 168] proc: process 7 exited with code 0 [ 168] sched: switch to 2:1 [ 169] sched: off-stack process stack release pid=7 stacks=1 reason=deferred-process-drop proof_result=ok [ 170] proc: spawned process 8 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 170] proc: process 8 session context profile='system' for request 'cap-slot-leak' [ 170] sched: added process 8 [ 171] sched: switch 2:1 -> 8:1 [ 171] syscall: unknown syscall 0 [ 171] pid=8 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 172] pid=8 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 172] pid=8 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 173] sched: process_cpu_accounting pid=8 runtime_ns=20857703 context_switches=3 [ 173] proc: process 8 exited with code 0 [ 173] sched: switch to 2:1 [ 174] sched: off-stack process stack release pid=8 stacks=1 reason=deferred-process-drop proof_result=ok [ 174] proc: spawned process 9 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 174] proc: process 9 session context profile='system' for request 'cap-slot-leak' [ 174] sched: added process 9 [ 175] sched: switch 2:1 -> 9:1 [ 175] syscall: unknown syscall 0 [ 176] pid=9 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 176] pid=9 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 177] pid=9 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 177] sched: process_cpu_accounting pid=9 runtime_ns=21272397 context_switches=3 [ 177] proc: process 9 exited with code 0 [ 177] sched: switch to 2:1 [ 178] sched: off-stack process stack release pid=9 stacks=1 reason=deferred-process-drop proof_result=ok [ 179] proc: spawned process 10 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 179] proc: process 10 session context profile='system' for request 'cap-slot-leak' [ 179] sched: added process 10 [ 180] sched: switch 2:1 -> 10:1 [ 180] syscall: unknown syscall 0 [ 180] pid=10 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 181] pid=10 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 181] pid=10 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 182] sched: process_cpu_accounting pid=10 runtime_ns=21234143 context_switches=3 [ 182] proc: process 10 exited with code 0 [ 182] sched: switch to 2:1 [ 183] sched: off-stack process stack release pid=10 stacks=1 reason=deferred-process-drop proof_result=ok [ 184] proc: spawned process 11 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 184] proc: process 11 session context profile='system' for request 'cap-slot-leak' [ 184] sched: added process 11 [ 185] sched: switch 2:1 -> 11:1 [ 186] syscall: unknown syscall 0 [ 186] pid=11 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 186] sched: process_cpu_accounting pid=11 runtime_ns=10418449 context_switches=2 [ 186] proc: process 11 exited with code 0 [ 186] pid=11 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 186] sched: switch to 2:1 [ 187] sched: off-stack process stack release pid=11 stacks=1 reason=deferred-process-drop proof_result=ok [ 188] proc: spawned process 12 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 188] proc: process 12 session context profile='system' for request 'cap-slot-leak' [ 188] sched: added process 12 [ 189] sched: switch 2:1 -> 12:1 [ 190] syscall: unknown syscall 0 [ 190] sched: process_cpu_accounting pid=12 runtime_ns=5301911 context_switches=2 [ 190] proc: process 12 exited with code 0 [ 190] pid=12 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 190] sched: switch to 2:1 [ 191] sched: off-stack process stack release pid=12 stacks=1 reason=deferred-process-drop proof_result=ok [ 192] proc: spawned process 13 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 192] proc: process 13 session context profile='system' for request 'cap-slot-leak' [ 192] sched: added process 13 [ 193] sched: switch 2:1 -> 13:1 [ 193] syscall: unknown syscall 0 [ 193] sched: process_cpu_accounting pid=13 runtime_ns=4918006 context_switches=1 [ 193] proc: process 13 exited with code 0 [ 193] pid=13 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 193] sched: switch to 2:1 [ 194] sched: off-stack process stack release pid=13 stacks=1 reason=deferred-process-drop proof_result=ok [ 195] proc: spawned process 14 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 195] proc: process 14 session context profile='system' for request 'cap-slot-leak' [ 195] sched: added process 14 [ 196] sched: switch 2:1 -> 14:1 [ 197] syscall: unknown syscall 0 [ 197] sched: process_cpu_accounting pid=14 runtime_ns=5542096 context_switches=2 [ 197] proc: process 14 exited with code 0 [ 197] pid=14 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 197] sched: switch to 2:1 [ 198] sched: off-stack process stack release pid=14 stacks=1 reason=deferred-process-drop proof_result=ok [ 198] proc: spawned process 15 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 198] proc: process 15 session context profile='system' for request 'cap-slot-leak' [ 198] sched: added process 15 [ 199] sched: switch 2:1 -> 15:1 [ 199] syscall: unknown syscall 0 [ 199] sched: process_cpu_accounting pid=15 runtime_ns=4932069 context_switches=1 [ 199] proc: process 15 exited with code 0 [ 199] pid=15 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 199] sched: switch to 2:1 [ 200] sched: off-stack process stack release pid=15 stacks=1 reason=deferred-process-drop proof_result=ok [ 201] proc: spawned process 16 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 201] proc: process 16 session context profile='system' for request 'cap-slot-leak' [ 201] sched: added process 16 [ 202] sched: switch 2:1 -> 16:1 [ 203] syscall: unknown syscall 0 [ 203] sched: process_cpu_accounting pid=16 runtime_ns=5348181 context_switches=2 [ 203] proc: process 16 exited with code 0 [ 203] pid=16 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 203] sched: switch to 2:1 [ 204] sched: off-stack process stack release pid=16 stacks=1 reason=deferred-process-drop proof_result=ok [ 204] proc: spawned process 17 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 204] proc: process 17 session context profile='system' for request 'cap-slot-leak' [ 204] sched: added process 17 [ 205] sched: switch 2:1 -> 17:1 [ 205] syscall: unknown syscall 0 [ 205] sched: process_cpu_accounting pid=17 runtime_ns=4977396 context_switches=1 [ 205] proc: process 17 exited with code 0 [ 205] pid=17 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 205] sched: switch to 2:1 [ 206] sched: off-stack process stack release pid=17 stacks=1 reason=deferred-process-drop proof_result=ok [ 207] proc: spawned process 18 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 207] proc: process 18 session context profile='system' for request 'cap-slot-leak' [ 207] sched: added process 18 [ 208] sched: switch 2:1 -> 18:1 [ 208] syscall: unknown syscall 0 [ 208] sched: process_cpu_accounting pid=18 runtime_ns=4932251 context_switches=1 [ 208] proc: process 18 exited with code 0 [ 208] pid=18 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 208] sched: switch to 2:1 [ 209] sched: off-stack process stack release pid=18 stacks=1 reason=deferred-process-drop proof_result=ok [ 210] proc: spawned process 19 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 210] proc: process 19 session context profile='system' for request 'cap-slot-leak' [ 210] sched: added process 19 [ 211] sched: switch 2:1 -> 19:1 [ 211] syscall: unknown syscall 0 [ 211] pid=19 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 212] pid=19 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 212] pid=19 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 213] sched: process_cpu_accounting pid=19 runtime_ns=21185383 context_switches=3 [ 213] proc: process 19 exited with code 0 [ 213] sched: switch to 2:1 [ 214] sched: off-stack process stack release pid=19 stacks=1 reason=deferred-process-drop proof_result=ok [ 215] proc: spawned process 20 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 215] proc: process 20 session context profile='system' for request 'cap-slot-leak' [ 215] sched: added process 20 [ 216] sched: switch 2:1 -> 20:1 [ 217] syscall: unknown syscall 0 [ 217] pid=20 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 218] pid=20 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 218] pid=20 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 219] sched: process_cpu_accounting pid=20 runtime_ns=21176536 context_switches=4 [ 219] proc: process 20 exited with code 0 [ 219] sched: switch to 2:1 [ 220] sched: off-stack process stack release pid=20 stacks=1 reason=deferred-process-drop proof_result=ok [ 220] proc: spawned process 21 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 220] proc: process 21 session context profile='system' for request 'cap-slot-leak' [ 220] sched: added process 21 [ 221] sched: switch 2:1 -> 21:1 [ 222] syscall: unknown syscall 0 [ 222] pid=21 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 222] pid=21 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 223] pid=21 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 223] sched: process_cpu_accounting pid=21 runtime_ns=20977975 context_switches=3 [ 223] proc: process 21 exited with code 0 [ 223] sched: switch to 2:1 [ 224] sched: off-stack process stack release pid=21 stacks=1 reason=deferred-process-drop proof_result=ok [ 225] proc: spawned process 22 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 225] proc: process 22 session context profile='system' for request 'cap-slot-leak' [ 225] sched: added process 22 [ 226] sched: switch 2:1 -> 22:1 [ 226] syscall: unknown syscall 0 [ 226] pid=22 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 227] pid=22 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 228] pid=22 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 228] sched: process_cpu_accounting pid=22 runtime_ns=20762589 context_switches=3 [ 228] proc: process 22 exited with code 0 [ 228] sched: switch to 2:1 [ 229] sched: off-stack process stack release pid=22 stacks=1 reason=deferred-process-drop proof_result=ok [ 230] proc: spawned process 23 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 230] proc: process 23 session context profile='system' for request 'cap-slot-leak' [ 230] sched: added process 23 [ 231] sched: switch 2:1 -> 23:1 [ 231] syscall: unknown syscall 0 [ 231] pid=23 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 232] pid=23 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 232] pid=23 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 233] sched: process_cpu_accounting pid=23 runtime_ns=20794544 context_switches=3 [ 233] proc: process 23 exited with code 0 [ 233] sched: switch to 2:1 [ 234] sched: off-stack process stack release pid=23 stacks=1 reason=deferred-process-drop proof_result=ok [ 235] proc: spawned process 24 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 235] proc: process 24 session context profile='system' for request 'cap-slot-leak' [ 235] sched: added process 24 [ 236] sched: switch 2:1 -> 24:1 [ 237] syscall: unknown syscall 0 [ 237] pid=24 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 237] sched: process_cpu_accounting pid=24 runtime_ns=10308630 context_switches=2 [ 237] proc: process 24 exited with code 0 [ 237] pid=24 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 237] sched: switch to 2:1 [ 238] sched: off-stack process stack release pid=24 stacks=1 reason=deferred-process-drop proof_result=ok [ 239] proc: spawned process 25 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 239] proc: process 25 session context profile='system' for request 'cap-slot-leak' [ 239] sched: added process 25 [ 240] sched: switch 2:1 -> 25:1 [ 240] syscall: unknown syscall 0 [ 240] sched: process_cpu_accounting pid=25 runtime_ns=4912172 context_switches=1 [ 240] proc: process 25 exited with code 0 [ 240] pid=25 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 240] sched: switch to 2:1 [ 241] sched: off-stack process stack release pid=25 stacks=1 reason=deferred-process-drop proof_result=ok [ 242] proc: spawned process 26 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 242] proc: process 26 session context profile='system' for request 'cap-slot-leak' [ 242] sched: added process 26 [ 243] sched: switch 2:1 -> 26:1 [ 244] syscall: unknown syscall 0 [ 244] sched: process_cpu_accounting pid=26 runtime_ns=5221966 context_switches=2 [ 244] proc: process 26 exited with code 0 [ 244] pid=26 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 244] sched: switch to 2:1 [ 245] sched: off-stack process stack release pid=26 stacks=1 reason=deferred-process-drop proof_result=ok [ 246] proc: spawned process 27 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 246] proc: process 27 session context profile='system' for request 'cap-slot-leak' [ 246] sched: added process 27 [ 247] sched: switch 2:1 -> 27:1 [ 247] syscall: unknown syscall 0 [ 248] sched: process_cpu_accounting pid=27 runtime_ns=5196449 context_switches=2 [ 248] proc: process 27 exited with code 0 [ 248] pid=27 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 248] sched: switch to 2:1 [ 249] sched: off-stack process stack release pid=27 stacks=1 reason=deferred-process-drop proof_result=ok [ 250] proc: spawned process 28 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 250] proc: process 28 session context profile='system' for request 'cap-slot-leak' [ 250] sched: added process 28 [ 251] sched: switch 2:1 -> 28:1 [ 251] syscall: unknown syscall 0 [ 251] sched: process_cpu_accounting pid=28 runtime_ns=4819204 context_switches=1 [ 251] proc: process 28 exited with code 0 [ 251] pid=28 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 251] sched: switch to 2:1 [ 252] sched: off-stack process stack release pid=28 stacks=1 reason=deferred-process-drop proof_result=ok [ 253] proc: spawned process 29 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 253] proc: process 29 session context profile='system' for request 'cap-slot-leak' [ 253] sched: added process 29 [ 254] sched: switch 2:1 -> 29:1 [ 255] syscall: unknown syscall 0 [ 255] sched: process_cpu_accounting pid=29 runtime_ns=5182156 context_switches=2 [ 255] proc: process 29 exited with code 0 [ 255] pid=29 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 255] sched: switch to 2:1 [ 256] sched: off-stack process stack release pid=29 stacks=1 reason=deferred-process-drop proof_result=ok [ 256] proc: spawned process 30 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 256] proc: process 30 session context profile='system' for request 'cap-slot-leak' [ 256] sched: added process 30 [ 257] sched: switch 2:1 -> 30:1 [ 257] syscall: unknown syscall 0 [ 258] sched: process_cpu_accounting pid=30 runtime_ns=5225775 context_switches=2 [ 258] proc: process 30 exited with code 0 [ 258] pid=30 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 258] sched: switch to 2:1 [ 259] sched: off-stack process stack release pid=30 stacks=1 reason=deferred-process-drop proof_result=ok [ 260] proc: spawned process 31 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 260] proc: process 31 session context profile='system' for request 'cap-slot-leak' [ 260] sched: added process 31 [ 261] sched: switch 2:1 -> 31:1 [ 261] syscall: unknown syscall 0 [ 261] sched: process_cpu_accounting pid=31 runtime_ns=4942720 context_switches=1 [ 261] proc: process 31 exited with code 0 [ 261] pid=31 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 261] sched: switch to 2:1 [ 262] sched: off-stack process stack release pid=31 stacks=1 reason=deferred-process-drop proof_result=ok [ 263] proc: spawned process 32 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 263] proc: process 32 session context profile='system' for request 'cap-slot-leak' [ 263] sched: added process 32 [ 264] sched: switch 2:1 -> 32:1 [ 264] syscall: unknown syscall 0 [ 264] sched: process_cpu_accounting pid=32 runtime_ns=4881415 context_switches=1 [ 264] proc: process 32 exited with code 0 [ 264] pid=32 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 264] sched: switch to 2:1 [ 265] sched: off-stack process stack release pid=32 stacks=1 reason=deferred-process-drop proof_result=ok [ 265] proc: spawned process 33 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 265] proc: process 33 session context profile='system' for request 'cap-slot-leak' [ 265] sched: added process 33 [ 266] sched: switch 2:1 -> 33:1 [ 266] syscall: unknown syscall 0 [ 267] sched: process_cpu_accounting pid=33 runtime_ns=5246229 context_switches=2 [ 267] proc: process 33 exited with code 0 [ 267] pid=33 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 267] sched: switch to 2:1 [ 268] sched: off-stack process stack release pid=33 stacks=1 reason=deferred-process-drop proof_result=ok [ 269] proc: spawned process 34 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 269] proc: process 34 session context profile='system' for request 'cap-slot-leak' [ 269] sched: added process 34 [ 270] sched: switch 2:1 -> 34:1 [ 270] syscall: unknown syscall 0 [ 270] sched: process_cpu_accounting pid=34 runtime_ns=5279690 context_switches=1 [ 270] proc: process 34 exited with code 0 [ 270] pid=34 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 270] sched: switch to 2:1 [ 271] sched: off-stack process stack release pid=34 stacks=1 reason=deferred-process-drop proof_result=ok [ 272] proc: spawned process 35 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 272] proc: process 35 session context profile='system' for request 'cap-slot-leak' [ 272] sched: added process 35 [ 273] sched: switch 2:1 -> 35:1 [ 273] syscall: unknown syscall 0 [ 273] sched: process_cpu_accounting pid=35 runtime_ns=4852420 context_switches=1 [ 273] proc: process 35 exited with code 0 [ 273] pid=35 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 273] sched: switch to 2:1 [ 274] sched: off-stack process stack release pid=35 stacks=1 reason=deferred-process-drop proof_result=ok [ 275] proc: spawned process 36 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 275] proc: process 36 session context profile='system' for request 'cap-slot-leak' [ 275] sched: added process 36 [ 276] sched: switch 2:1 -> 36:1 [ 277] syscall: unknown syscall 0 [ 277] pid=36 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 277] pid=36 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 278] pid=36 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 279] sched: process_cpu_accounting pid=36 runtime_ns=21321236 context_switches=4 [ 279] proc: process 36 exited with code 0 [ 279] sched: switch to 2:1 [ 280] sched: off-stack process stack release pid=36 stacks=1 reason=deferred-process-drop proof_result=ok [ 280] proc: spawned process 37 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 280] proc: process 37 session context profile='system' for request 'cap-slot-leak' [ 280] sched: added process 37 [ 281] sched: switch 2:1 -> 37:1 [ 281] syscall: unknown syscall 0 [ 282] pid=37 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 282] pid=37 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 283] pid=37 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 283] sched: process_cpu_accounting pid=37 runtime_ns=20958089 context_switches=3 [ 283] proc: process 37 exited with code 0 [ 283] sched: switch to 2:1 [ 284] sched: off-stack process stack release pid=37 stacks=1 reason=deferred-process-drop proof_result=ok [ 285] proc: spawned process 38 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 285] proc: process 38 session context profile='system' for request 'cap-slot-leak' [ 285] sched: added process 38 [ 286] sched: switch 2:1 -> 38:1 [ 286] syscall: unknown syscall 0 [ 286] pid=38 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 287] pid=38 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 287] pid=38 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 288] sched: process_cpu_accounting pid=38 runtime_ns=20777617 context_switches=3 [ 288] proc: process 38 exited with code 0 [ 288] sched: switch to 2:1 [ 289] sched: off-stack process stack release pid=38 stacks=1 reason=deferred-process-drop proof_result=ok [ 290] proc: spawned process 39 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 290] proc: process 39 session context profile='system' for request 'cap-slot-leak' [ 290] sched: added process 39 [ 291] sched: switch 2:1 -> 39:1 [ 291] syscall: unknown syscall 0 [ 291] pid=39 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 292] pid=39 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 292] pid=39 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 293] sched: process_cpu_accounting pid=39 runtime_ns=21039639 context_switches=3 [ 293] proc: process 39 exited with code 0 [ 293] sched: switch to 2:1 [ 294] sched: off-stack process stack release pid=39 stacks=1 reason=deferred-process-drop proof_result=ok [ 295] proc: spawned process 40 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 295] proc: process 40 session context profile='system' for request 'cap-slot-leak' [ 295] sched: added process 40 [ 296] sched: switch 2:1 -> 40:1 [ 297] syscall: unknown syscall 0 [ 297] pid=40 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 297] pid=40 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 298] pid=40 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 299] sched: process_cpu_accounting pid=40 runtime_ns=21625199 context_switches=4 [ 299] proc: process 40 exited with code 0 [ 299] sched: switch to 2:1 [ 300] sched: off-stack process stack release pid=40 stacks=1 reason=deferred-process-drop proof_result=ok [ 300] proc: spawned process 41 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 300] proc: process 41 session context profile='system' for request 'cap-slot-leak' [ 300] sched: added process 41 [ 301] sched: switch 2:1 -> 41:1 [ 301] syscall: unknown syscall 0 [ 302] pid=41 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 302] sched: process_cpu_accounting pid=41 runtime_ns=10308228 context_switches=2 [ 302] proc: process 41 exited with code 0 [ 302] pid=41 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 302] sched: switch to 2:1 [ 303] sched: off-stack process stack release pid=41 stacks=1 reason=deferred-process-drop proof_result=ok [ 304] proc: spawned process 42 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 304] proc: process 42 session context profile='system' for request 'cap-slot-leak' [ 304] sched: added process 42 [ 305] sched: switch 2:1 -> 42:1 [ 306] syscall: unknown syscall 0 [ 306] sched: process_cpu_accounting pid=42 runtime_ns=5247505 context_switches=2 [ 306] proc: process 42 exited with code 0 [ 306] pid=42 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 306] sched: switch to 2:1 [ 307] sched: off-stack process stack release pid=42 stacks=1 reason=deferred-process-drop proof_result=ok [ 307] proc: spawned process 43 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 307] proc: process 43 session context profile='system' for request 'cap-slot-leak' [ 307] sched: added process 43 [ 308] sched: switch 2:1 -> 43:1 [ 308] syscall: unknown syscall 0 [ 309] sched: process_cpu_accounting pid=43 runtime_ns=5293930 context_switches=2 [ 309] proc: process 43 exited with code 0 [ 309] pid=43 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 309] sched: switch to 2:1 [ 310] sched: off-stack process stack release pid=43 stacks=1 reason=deferred-process-drop proof_result=ok [ 311] proc: spawned process 44 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 311] proc: process 44 session context profile='system' for request 'cap-slot-leak' [ 311] sched: added process 44 [ 312] sched: switch 2:1 -> 44:1 [ 312] syscall: unknown syscall 0 [ 312] sched: process_cpu_accounting pid=44 runtime_ns=4909196 context_switches=1 [ 312] proc: process 44 exited with code 0 [ 312] pid=44 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 312] sched: switch to 2:1 [ 313] sched: off-stack process stack release pid=44 stacks=1 reason=deferred-process-drop proof_result=ok [ 314] proc: spawned process 45 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 314] proc: process 45 session context profile='system' for request 'cap-slot-leak' [ 314] sched: added process 45 [ 315] sched: switch 2:1 -> 45:1 [ 315] syscall: unknown syscall 0 [ 315] sched: process_cpu_accounting pid=45 runtime_ns=4880539 context_switches=1 [ 315] proc: process 45 exited with code 0 [ 315] pid=45 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 315] sched: switch to 2:1 [ 316] sched: off-stack process stack release pid=45 stacks=1 reason=deferred-process-drop proof_result=ok [ 316] proc: spawned process 46 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 316] proc: process 46 session context profile='system' for request 'cap-slot-leak' [ 316] sched: added process 46 [ 317] sched: switch 2:1 -> 46:1 [ 317] syscall: unknown syscall 0 [ 318] sched: process_cpu_accounting pid=46 runtime_ns=5268647 context_switches=2 [ 318] proc: process 46 exited with code 0 [ 318] pid=46 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 318] sched: switch to 2:1 [ 319] sched: off-stack process stack release pid=46 stacks=1 reason=deferred-process-drop proof_result=ok [ 320] proc: spawned process 47 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 320] proc: process 47 session context profile='system' for request 'cap-slot-leak' [ 320] sched: added process 47 [ 321] sched: switch 2:1 -> 47:1 [ 321] syscall: unknown syscall 0 [ 322] sched: process_cpu_accounting pid=47 runtime_ns=5278973 context_switches=2 [ 322] proc: process 47 exited with code 0 [ 322] pid=47 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 322] sched: switch to 2:1 [ 323] sched: off-stack process stack release pid=47 stacks=1 reason=deferred-process-drop proof_result=ok [ 324] proc: spawned process 48 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 324] proc: process 48 session context profile='system' for request 'cap-slot-leak' [ 324] sched: added process 48 [ 325] sched: switch 2:1 -> 48:1 [ 325] syscall: unknown syscall 0 [ 325] sched: process_cpu_accounting pid=48 runtime_ns=4909854 context_switches=1 [ 325] proc: process 48 exited with code 0 [ 325] pid=48 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 325] sched: switch to 2:1 [ 326] sched: off-stack process stack release pid=48 stacks=1 reason=deferred-process-drop proof_result=ok [ 327] proc: spawned process 49 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 327] proc: process 49 session context profile='system' for request 'cap-slot-leak' [ 327] sched: added process 49 [ 328] sched: switch 2:1 -> 49:1 [ 329] syscall: unknown syscall 0 [ 329] sched: process_cpu_accounting pid=49 runtime_ns=5261586 context_switches=2 [ 329] proc: process 49 exited with code 0 [ 329] pid=49 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 329] sched: switch to 2:1 [ 330] sched: off-stack process stack release pid=49 stacks=1 reason=deferred-process-drop proof_result=ok [ 330] proc: spawned process 50 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 330] proc: process 50 session context profile='system' for request 'cap-slot-leak' [ 330] sched: added process 50 [ 331] sched: switch 2:1 -> 50:1 [ 331] syscall: unknown syscall 0 [ 332] sched: process_cpu_accounting pid=50 runtime_ns=5239139 context_switches=2 [ 332] proc: process 50 exited with code 0 [ 332] pid=50 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 332] sched: switch to 2:1 [ 333] sched: off-stack process stack release pid=50 stacks=1 reason=deferred-process-drop proof_result=ok [ 334] proc: spawned process 51 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 334] proc: process 51 session context profile='system' for request 'cap-slot-leak' [ 334] sched: added process 51 [ 335] sched: switch 2:1 -> 51:1 [ 335] syscall: unknown syscall 0 [ 335] sched: process_cpu_accounting pid=51 runtime_ns=4901493 context_switches=1 [ 335] proc: process 51 exited with code 0 [ 335] pid=51 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 335] sched: switch to 2:1 [ 336] sched: off-stack process stack release pid=51 stacks=1 reason=deferred-process-drop proof_result=ok [ 337] proc: spawned process 52 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 337] proc: process 52 session context profile='system' for request 'cap-slot-leak' [ 337] sched: added process 52 [ 338] sched: switch 2:1 -> 52:1 [ 338] syscall: unknown syscall 0 [ 338] sched: process_cpu_accounting pid=52 runtime_ns=4888710 context_switches=1 [ 338] proc: process 52 exited with code 0 [ 338] pid=52 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 338] sched: switch to 2:1 [ 339] sched: off-stack process stack release pid=52 stacks=1 reason=deferred-process-drop proof_result=ok [ 339] proc: spawned process 53 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 339] proc: process 53 session context profile='system' for request 'cap-slot-leak' [ 339] sched: added process 53 [ 340] sched: switch 2:1 -> 53:1 [ 341] syscall: unknown syscall 0 [ 341] pid=53 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 341] pid=53 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 342] pid=53 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 342] sched: process_cpu_accounting pid=53 runtime_ns=20941168 context_switches=3 [ 342] proc: process 53 exited with code 0 [ 342] sched: switch to 2:1 [ 343] sched: off-stack process stack release pid=53 stacks=1 reason=deferred-process-drop proof_result=ok [ 344] proc: spawned process 54 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 344] proc: process 54 session context profile='system' for request 'cap-slot-leak' [ 344] sched: added process 54 [ 345] sched: switch 2:1 -> 54:1 [ 345] syscall: unknown syscall 0 [ 346] pid=54 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 346] pid=54 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 347] pid=54 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 347] sched: process_cpu_accounting pid=54 runtime_ns=21502124 context_switches=3 [ 347] proc: process 54 exited with code 0 [ 347] sched: switch to 2:1 [ 348] sched: off-stack process stack release pid=54 stacks=1 reason=deferred-process-drop proof_result=ok [ 349] proc: spawned process 55 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 349] proc: process 55 session context profile='system' for request 'cap-slot-leak' [ 349] sched: added process 55 [ 350] sched: switch 2:1 -> 55:1 [ 350] syscall: unknown syscall 0 [ 350] pid=55 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 351] pid=55 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 351] pid=55 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 352] sched: process_cpu_accounting pid=55 runtime_ns=20914153 context_switches=3 [ 352] proc: process 55 exited with code 0 [ 352] sched: switch to 2:1 [ 353] sched: off-stack process stack release pid=55 stacks=1 reason=deferred-process-drop proof_result=ok [ 354] proc: spawned process 56 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 354] proc: process 56 session context profile='system' for request 'cap-slot-leak' [ 354] sched: added process 56 [ 355] sched: switch 2:1 -> 56:1 [ 355] syscall: unknown syscall 0 [ 355] pid=56 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 356] pid=56 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 356] pid=56 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 357] sched: process_cpu_accounting pid=56 runtime_ns=20768151 context_switches=3 [ 357] proc: process 56 exited with code 0 [ 357] sched: switch to 2:1 [ 358] sched: off-stack process stack release pid=56 stacks=1 reason=deferred-process-drop proof_result=ok [ 358] proc: spawned process 57 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 358] proc: process 57 session context profile='system' for request 'cap-slot-leak' [ 358] sched: added process 57 [ 359] sched: switch 2:1 -> 57:1 [ 359] syscall: unknown syscall 0 [ 360] pid=57 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 360] pid=57 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 361] pid=57 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 361] sched: process_cpu_accounting pid=57 runtime_ns=20811192 context_switches=3 [ 361] proc: process 57 exited with code 0 [ 361] sched: switch to 2:1 [ 362] sched: off-stack process stack release pid=57 stacks=1 reason=deferred-process-drop proof_result=ok [ 363] proc: spawned process 58 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 363] proc: process 58 session context profile='system' for request 'cap-slot-leak' [ 363] sched: added process 58 [ 364] sched: switch 2:1 -> 58:1 [ 364] syscall: unknown syscall 0 [ 364] pid=58 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 365] sched: process_cpu_accounting pid=58 runtime_ns=10327469 context_switches=2 [ 365] proc: process 58 exited with code 0 [ 365] pid=58 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 365] sched: switch to 2:1 [ 366] sched: off-stack process stack release pid=58 stacks=1 reason=deferred-process-drop proof_result=ok [ 366] proc: spawned process 59 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 366] proc: process 59 session context profile='system' for request 'cap-slot-leak' [ 366] sched: added process 59 [ 367] sched: switch 2:1 -> 59:1 [ 367] syscall: unknown syscall 0 [ 368] sched: process_cpu_accounting pid=59 runtime_ns=5230101 context_switches=2 [ 368] proc: process 59 exited with code 0 [ 368] pid=59 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 368] sched: switch to 2:1 [ 369] sched: off-stack process stack release pid=59 stacks=1 reason=deferred-process-drop proof_result=ok [ 370] proc: spawned process 60 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 370] proc: process 60 session context profile='system' for request 'cap-slot-leak' [ 370] sched: added process 60 [ 371] sched: switch 2:1 -> 60:1 [ 371] syscall: unknown syscall 0 [ 371] sched: process_cpu_accounting pid=60 runtime_ns=4857724 context_switches=1 [ 371] proc: process 60 exited with code 0 [ 371] pid=60 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 371] sched: switch to 2:1 [ 372] sched: off-stack process stack release pid=60 stacks=1 reason=deferred-process-drop proof_result=ok [ 373] proc: spawned process 61 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 373] proc: process 61 session context profile='system' for request 'cap-slot-leak' [ 373] sched: added process 61 [ 374] sched: switch 2:1 -> 61:1 [ 374] syscall: unknown syscall 0 [ 374] sched: process_cpu_accounting pid=61 runtime_ns=4901229 context_switches=1 [ 374] proc: process 61 exited with code 0 [ 374] pid=61 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 374] sched: switch to 2:1 [ 375] sched: off-stack process stack release pid=61 stacks=1 reason=deferred-process-drop proof_result=ok [ 375] proc: spawned process 62 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 375] proc: process 62 session context profile='system' for request 'cap-slot-leak' [ 375] sched: added process 62 [ 376] sched: switch 2:1 -> 62:1 [ 376] syscall: unknown syscall 0 [ 377] sched: process_cpu_accounting pid=62 runtime_ns=5253964 context_switches=2 [ 377] proc: process 62 exited with code 0 [ 377] pid=62 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 377] sched: switch to 2:1 [ 378] sched: off-stack process stack release pid=62 stacks=1 reason=deferred-process-drop proof_result=ok [ 379] proc: spawned process 63 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 379] proc: process 63 session context profile='system' for request 'cap-slot-leak' [ 379] sched: added process 63 [ 380] sched: switch 2:1 -> 63:1 [ 380] syscall: unknown syscall 0 [ 380] sched: process_cpu_accounting pid=63 runtime_ns=4903236 context_switches=1 [ 380] proc: process 63 exited with code 0 [ 380] pid=63 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 380] sched: switch to 2:1 [ 381] sched: off-stack process stack release pid=63 stacks=1 reason=deferred-process-drop proof_result=ok [ 382] proc: spawned process 64 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 382] proc: process 64 session context profile='system' for request 'cap-slot-leak' [ 382] sched: added process 64 [ 383] sched: switch 2:1 -> 64:1 [ 383] syscall: unknown syscall 0 [ 383] sched: process_cpu_accounting pid=64 runtime_ns=4838807 context_switches=1 [ 383] proc: process 64 exited with code 0 [ 383] pid=64 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 383] sched: switch to 2:1 [ 384] sched: off-stack process stack release pid=64 stacks=1 reason=deferred-process-drop proof_result=ok [ 384] proc: spawned process 65 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 384] proc: process 65 session context profile='system' for request 'cap-slot-leak' [ 384] sched: added process 65 [ 385] sched: switch 2:1 -> 65:1 [ 386] syscall: unknown syscall 0 [ 386] sched: process_cpu_accounting pid=65 runtime_ns=5251308 context_switches=2 [ 386] proc: process 65 exited with code 0 [ 386] pid=65 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 386] sched: switch to 2:1 [ 387] sched: off-stack process stack release pid=65 stacks=1 reason=deferred-process-drop proof_result=ok [init] Spawn cap-table exhaustion check ok. [ 389] sched: process_cpu_accounting pid=2 runtime_ns=2746502727 context_switches=179 [ 389] proc: process 2 exited with code 0 [ 389] sched: last process exited, halting measure: ring dispatches=1145 sqes=5514 nop_sqes=4227 measure: sqe_throughput sqes=4096 elapsed_ns=89783588 ops_per_second=45620 measure: serial bytes=84528 measure: timer interrupts=389 user_scheduler=368 kernel_only=21 bsp_tick_advances=389 measure: tlb cr3_writes=400 pending_flush_checks=3119 pending_flush_all=0 shootdown_requests=0 shootdown_target_cpus=0 shootdown_ipis=0 deferred_completion_drains=0 measure: network_poll scheduler_polls=0 runtime_polls=0 iface_polls=0 tcp_http_polls=0 queue_poll_spins=0 queue_completions=0 waiter_scans=0 waiter_slots=0 measure: thread_placement selected_cpu0=0 selected_cpu1=0 selected_cpu2=0 selected_cpu3=0 first_selected_cpu0=0 first_selected_cpu1=0 first_selected_cpu2=0 first_selected_cpu3=0 migrations=0 migration_to_cpu0=0 migration_to_cpu1=0 migration_to_cpu2=0 migration_to_cpu3=0 measure: segment ring_processing count=5520 cycles=7532261578 avg=1364540 measure: segment sqe_validation count=6380 cycles=44325010 avg=6947 measure: segment cap_lookup count=1696 cycles=979097895 avg=577298 measure: segment capnp_decode_encode count=650 cycles=4095734 avg=6301 measure: segment method_body count=764 cycles=6344222550 avg=8303956 measure: segment null_cap_empty count=80 cycles=4790 avg=59 measure: segment null_cap_schema count=80 cycles=273078 avg=3413 measure: schema_dispatch_overhead null_empty_count=80 null_empty_avg=59 null_schema_count=80 null_schema_avg=3413 overhead_cycles=3354 measure: cap_slot_memory slot_bytes=80 max_slots=256 max_slot_storage_bytes=20480 measure: segment cqe_post count=5502 cycles=1715256 avg=311 measure: segment waiter_wake_check count=5489 cycles=23737726 avg=4324 measure: segment sched_choose_next count=636 cycles=52915754 avg=83200 measure: direct_ipc_ready_to_select count=40 cycles=15788800 avg=394720 min=381170 max=468653 measure: endpoint_scheduler_matched direct_count=32 direct_cycles=12553542 direct_avg=392298 ordinary_count=31 ordinary_cycles=12432783 ordinary_avg=401057 measure: segment schedule_save_requeue count=368 cycles=64285063 avg=174687 measure: segment timer_sleep_wake count=1026 cycles=198894 avg=193 measure: segment park_wake count=1026 cycles=7187910 avg=7005 measure: segment cap_wait_wake_scan count=1038 cycles=2590102 avg=2495 measure: segment thread_exit_join_cleanup count=87 cycles=191917811 avg=2205951 measure: segment process_exit_cleanup count=64 cycles=713359256 avg=11146238 measure: segment process_drop count=63 cycles=1159338716 avg=18402201 measure: scheduler choose_direct_candidates=40 choose_run_queue_candidates=573 choose_idle_candidates=8 choose_runnable=621 choose_retry=0 choose_drop=0 wake_scans=245 wake_eligible_idle_cpus=0 wake_target_cpus=0 wake_ipis_sent=0 wake_pending_ipis_skipped=0 wake_target_not_ready_skipped=0 wake_lapic_targets_missing=0 wake_ipis_failed=0 measure: scheduler_lock acquisitions=25141 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=4936868684 measure: scheduler_lock_site schedule_pre_ring acquisitions=1148 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=212175600 measure: scheduler_lock_site schedule_select acquisitions=368 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=965263302 measure: scheduler_lock_site block_current acquisitions=8152 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=1320165821 measure: scheduler_lock_site wake_unblock acquisitions=5524 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=23761379 measure: scheduler_lock_site metadata acquisitions=9459 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=515915906 measure: shared_kernel_lock frame_alloc acquisitions=53620 contended=0 spin_loops=0 measure: shared_kernel_lock frame_free acquisitions=52796 contended=0 spin_loops=0 measure: shared_kernel_lock ring_dispatch_cap_table acquisitions=368 contended=0 spin_loops=0 measure: shared_kernel_lock ring_dispatch_scratch acquisitions=368 contended=0 spin_loops=0 measure: shared_kernel_lock endpoint_inner acquisitions=374 contended=0 spin_loops=0 measure: shared_kernel_lock endpoint_cancel_scratch acquisitions=86 contended=0 spin_loops=0 measure: shared_kernel_lock address_space acquisitions=3882 contended=0 spin_loops=0 measure: shared_kernel_lock heap acquisitions=5463 contended=0 spin_loops=0 measure: user_pc_samples total=0 overflow=0