[ 0] capos kernel starting... [ 0] smp: BSP per-cpu active cpu=0 lapic=0 base=0xffffffff812a9000 syscall_rsp=0xffffffff812b15f0 [ 0] syscall: entry at 0xffffffff805b3984 [ 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 - 0x0000000009f74000 0 [ 0] 0x0000000009f74000 - 0x0000000009fc5000 6 [ 0] 0x0000000009fc5000 - 0x0000000009fc6000 5 [ 0] 0x0000000009fc6000 - 0x0000000009fc7000 0 [ 0] 0x0000000009fc7000 - 0x0000000009fc8000 5 [ 0] 0x0000000009fc8000 - 0x0000000009fc9000 0 [ 0] 0x0000000009fc9000 - 0x0000000009fd2000 5 [ 0] 0x0000000009fd2000 - 0x000000000b3c0000 6 [ 0] 0x000000000b3c0000 - 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: 162256 KB (40564 frames) [ 0] heap: 98304 KB across 1 physical runs / 2 allocator regions (14 region slots free) [ 0] mem: frame allocator + heap initialized (16080 usable frames) [ 0] alloc test: [1, 2, 3, 4, 5] [ 0] paging: kernel sections: [ 0] .text: 0xffffffff80000000 - 0xffffffff808bab0f [ 0] .rodata: 0xffffffff808bb000 - 0xffffffff80a37460 [ 0] .data: 0xffffffff80a38000 - 0xffffffff812a8ac0 [ 0] .bss: 0xffffffff812a9000 - 0xffffffff813edf98 [ 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=0xffffffff803c4850 kernel_rsp=0xffff800006130000 cs=0x8 ss=0x10 [ 0] sched: CPL0 idle context cpu=1 entry=0xffffffff803c4850 kernel_rsp=0xffff800006150000 cs=0x8 ss=0x10 [ 0] sched: CPL0 idle context cpu=2 entry=0xffffffff803c4850 kernel_rsp=0xffff800006170000 cs=0x8 ss=0x10 [ 0] sched: CPL0 idle context cpu=3 entry=0xffffffff803c4850 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=628331 divide=16 calibrated_hz=100 clocksource=tsc-calibrated calibration_ns=10000150 tsc_delta=28107264 [ 0] clockevent: proof backend=lapic oneshot=programmed deadline_ns=14453816 delta_ns=5000000 count=314166 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=5080895 count=314166 count_after_program=312514 periodic_initial_count=628331 within_tolerance=true restored_periodic=true periodic_live=true [ 0] clockevent-floor: clocksource=tsc real_ns=5000025 monotonic_delta_ns=5000087 tick_ns=10000000 floored=false subtick_tracked=true [ 0] smp: no AP slots prepared; AP bootstrap skipped [ 0] manifest: loaded 331584 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 (737600 bytes) [ 0] binary 'init': 737600 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=21457 KiB free=76846 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. [ 5] proc: spawned process 3 for request 'ring-nop' (binary='ring-nop', 4 caps) [ 5] proc: process 3 session context profile='system' for request 'ring-nop' [ 5] sched: added process 3 [ 6] sched: switch 2:1 -> 3:1 [ 6] pid=3 invalid submission err=-1 opcode=5 cap_id_bucket=0 cause=structural [ 6] pid=3 invalid submission err=-1 opcode=5 cap_id_bucket=0 cause=structural [ 7] pid=3 invalid submission err=-1 opcode=5 cap_id_bucket=0 cause=structural [ 7] pid=3 invalid submission err=-2 opcode=0 cap_id_bucket=0 cause=structural [ 8] pid=3 invalid submission err=-3 opcode=0 cap_id_bucket=0 cause=structural [ 8] ring: failed to serialize CapException into result buffer: Failed: result buffer too small [ 9] pid=3 invalid submission err=-2 opcode=0 cap_id_bucket=0 cause=structural [ 10] pid=3 invalid submission err=-4 opcode=0 cap_id_bucket=4294967295 cause=cap-not-found [ 10] pid=3 invalid submission err=-4 opcode=1 cap_id_bucket=0 cause=cap-invoke-error [ring-nop] CAP_OP_NOP smoke ok. [ 12] sched: switch 3:1 -> 2:1 [ 12] proc: spawned process 4 for request 'thread-lifecycle' (binary='thread-lifecycle', 8 caps) [ 12] proc: process 4 session context profile='system' for request 'thread-lifecycle' [ 12] sched: added process 4 [ 13] sched: switch 2:1 -> 4:1 [ 14] sched: switch 4:1 -> 4:2 [ 16] sched: switch 4:2 -> 4:1 [ 16] sched: blocked 4:1 -> 4:2 [ 17] sched: switch 4:2 -> 4:1 [ 17] sched: blocked 4:1 -> 3:1 [ring-nop] measure avg cycles: nop=33894 null_empty=37759 null_small=5111471 [ 18] sched: switch 3:1 -> 4:2 [ 18] sched: switch to 4:1 [ 19] sched: off-stack thread stack release pid=4 tid=2 reason=thread-exit proof_result=ok [ 19] sched: switch 4:1 -> 4:3 [ 22] sched: switch 4:3 -> 3:1 [ 23] sched: switch 3:1 -> 4:1 [ 24] sched: switch 4:1 -> 4:3 [ 24] sched: switch to 3:1 [ 25] sched: off-stack thread stack release pid=4 tid=3 reason=thread-exit proof_result=ok [ 25] sched: switch 3:1 -> 4:1 [ 27] sched: switch 4:1 -> 4:4 [ 29] sched: switch 4:4 -> 3:1 [ 30] sched: switch 3:1 -> 4:4 [ 31] sched: switch 4:4 -> 4:1 [ 31] sched: blocked 4:1 -> 4:4 [ 32] sched: switch 4:4 -> 4:1 [ 33] sched: switch 4:1 -> 3:1 [ 34] sched: switch 3:1 -> 4:1 [ 35] sched: switch 4:1 -> 4:4 [ 35] sched: blocked 4:4 -> 4:1 [ 36] sched: switch 4:1 -> 4:4 [ 36] sched: switch to 3:1 [ 37] sched: off-stack thread stack release pid=4 tid=4 reason=thread-exit proof_result=ok [ 37] sched: switch 3:1 -> 4:1 [thread-lifecycle] park handoff wake retry ok. [ 39] sched: switch 4:1 -> 4:6 [ 39] sched: blocked 4:6 -> 4:5 [ 39] sched: blocked 4:5 -> 3:1 [ 40] sched: switch 3:1 -> 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 -> 4:1 [ 43] sched: blocked 4:1 -> 3:1 [ring-nop] park bench avg cycles: compact_wait_miss=36656 generic_wait_miss=4586180 compact_wake_empty=35451 generic_wake_empty=6438311 [ 45] sched: switch 3:1 -> 5:1 [ 45] sched: process_cpu_accounting pid=5 runtime_ns=23229384 context_switches=3 [ 45] proc: process 5 exited with code 0 [ 45] sched: switch to 4:1 [ 46] sched: off-stack process stack release pid=5 stacks=1 reason=deferred-process-drop proof_result=ok [ 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 3:1 [ 49] sched: off-stack thread stack release pid=4 tid=6 reason=thread-exit proof_result=ok [ 49] sched: switch 3:1 -> 4:1 [ 50] sched: switch 4:1 -> 3:1 [ 50] sched: process_cpu_accounting pid=3 runtime_ns=162243159 context_switches=17 [ 50] proc: process 3 exited with code 0 [ 50] sched: switch to 4:1 [ 51] sched: off-stack process stack release pid=3 stacks=1 reason=deferred-process-drop proof_result=ok [ 53] sched: switch 4:1 -> 4:8 [ 53] sched: blocked 4:8 -> 4:7 [ 53] sched: blocked 4:7 -> 4:1 [ 53] sched: blocked 4:1 -> 2:1 [init] Generic manifest child wait ok: unprivileged-stranger [ 54] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 54] sched: blocked 2:1 -> 1:1 [ 55] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=1 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 56] sched: cpl0-idle cpu=0 starting thread 4:1 [ 57] sched: switch 4:1 -> 4:7 [ 57] sched: switch to 4:8 [ 57] sched: off-stack thread stack release pid=4 tid=7 reason=thread-exit proof_result=ok [ 57] sched: switch to 4:1 [ 58] sched: off-stack thread stack release pid=4 tid=8 reason=thread-exit proof_result=ok [thread-lifecycle] park multi-waiter unmap reuse cleanup ok. [ 60] sched: switch 4:1 -> 4:9 [ 60] sched: blocked 4:9 -> 4:1 [ 60] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 60] sched: blocked 4:1 -> 1:1 [ 61] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=2 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 62] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=3 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 63] sched: cpl0-idle cpu=0 starting thread 4:1 [ 64] sched: switch 4:1 -> 4:9 [ 64] sched: switch to 4:1 [ 65] sched: off-stack thread stack release pid=4 tid=9 reason=thread-exit proof_result=ok [ 65] sched: switch 4:1 -> 4:10 [ 65] sched: blocked 4:10 -> 4:1 [ 66] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 66] sched: blocked 4:1 -> 1:1 [ 66] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=4 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 67] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=5 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 68] sched: cpl0-idle cpu=0 starting thread 4:1 [ 70] sched: switch 4:1 -> 4:10 [ 70] sched: switch to 4:1 [thread-lifecycle] park unmap/decommit/memoryobject cleanup ok. [ 71] sched: off-stack thread stack release pid=4 tid=10 reason=thread-exit proof_result=ok [ 72] sched: switch 4:1 -> 4:11 [ 74] sched: switch 4:11 -> 4:1 [ 75] sched: switch 4:1 -> 4:11 [ 75] sched: blocked 4:11 -> 4:1 [ 75] sched: dispatch idle cpu=0 idle_path=cpl0-dispatch-block [ 75] sched: blocked 4:1 -> 1:1 [ 76] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=6 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 77] cpu-isolation: kernel-idle hlt cpu=0 idle_path=cooperative-cpl0 entries=7 nohz_active=false timer_source=periodic-tick tickless_idle_active=false [ 78] sched: cpl0-idle cpu=0 starting thread 4:1 [ 79] 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=8 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 [ 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=243823 empty_wake=241237 wait_to_block=87667426 wake_to_runnable=437726 wake_to_resume=24845815 [ 115] sched: off-stack thread stack release pid=4 tid=11 reason=thread-exit proof_result=ok [ 116] sched: blocked 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 [ 118] sched: blocked 4:1 -> 4:12 [ 118] sched: blocked 4:12 -> 4:1 [ 118] 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 [ 119] 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 [ 121] 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 [ 123] 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 [ 125] 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 [ 128] 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 [ 133] 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 [ 135] sched: blocked 4:12 -> 4:1 [ 135] sched: blocked 4:1 -> 4:12 [ 135] 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 [ 136] 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 [ 143] 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 [ 145] 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: blocked 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 [ 148] sched: blocked 4:1 -> 4:12 [ 148] sched: blocked 4:12 -> 4:1 [ 148] 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 [ 149] 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 [ 151] 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 [ 152] sched: blocked 4:12 -> 4:1 [ 153] sched: blocked 4:1 -> 4:12 [ 153] sched: blocked 4:12 -> 4:1 [ 153] 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 [ 154] 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: blocked 4:12 -> 4:1 [ 156] sched: blocked 4:1 -> 4:12 [ 156] sched: blocked 4:12 -> 4:1 [ 157] sched: blocked 4:1 -> 4:12 [ 157] sched: switch to 4:1 [thread-lifecycle] endpoint CALL-to-receiver avg cycles: 7969332 [ 158] sched: off-stack thread stack release pid=4 tid=12 reason=thread-exit proof_result=ok [thread-lifecycle] thread lifecycle checkpoint ok. [ 159] sched: process_cpu_accounting pid=4 runtime_ns=1040274753 context_switches=288 [ 159] proc: process 4 exited with code 0 [ 159] sched: switch to 2:1 [ 160] 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. [ 162] proc: spawned process 6 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 162] proc: process 6 session context profile='system' for request 'cap-slot-leak' [ 162] sched: added process 6 [ 163] sched: switch 2:1 -> 6:1 [ 164] syscall: unknown syscall 0 [ 164] pid=6 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 165] pid=6 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 165] pid=6 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 166] sched: process_cpu_accounting pid=6 runtime_ns=21356643 context_switches=4 [ 166] proc: process 6 exited with code 0 [ 166] sched: switch to 2:1 [ 167] sched: off-stack process stack release pid=6 stacks=1 reason=deferred-process-drop proof_result=ok [ 167] proc: spawned process 7 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 167] proc: process 7 session context profile='system' for request 'cap-slot-leak' [ 167] sched: added process 7 [ 168] sched: switch 2:1 -> 7:1 [ 168] syscall: unknown syscall 0 [ 168] pid=7 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 169] pid=7 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 170] pid=7 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 170] sched: process_cpu_accounting pid=7 runtime_ns=20763227 context_switches=3 [ 170] proc: process 7 exited with code 0 [ 170] sched: switch to 2:1 [ 171] sched: off-stack process stack release pid=7 stacks=1 reason=deferred-process-drop proof_result=ok [ 172] proc: spawned process 8 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 172] proc: process 8 session context profile='system' for request 'cap-slot-leak' [ 172] sched: added process 8 [ 173] sched: switch 2:1 -> 8:1 [ 173] syscall: unknown syscall 0 [ 173] pid=8 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 174] pid=8 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 174] pid=8 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 175] sched: process_cpu_accounting pid=8 runtime_ns=20752406 context_switches=3 [ 175] proc: process 8 exited with code 0 [ 175] sched: switch to 2:1 [ 176] sched: off-stack process stack release pid=8 stacks=1 reason=deferred-process-drop proof_result=ok [ 176] proc: spawned process 9 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 176] proc: process 9 session context profile='system' for request 'cap-slot-leak' [ 176] sched: added process 9 [ 177] sched: switch 2:1 -> 9:1 [ 177] syscall: unknown syscall 0 [ 178] pid=9 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 178] pid=9 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 179] pid=9 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 179] sched: process_cpu_accounting pid=9 runtime_ns=20813358 context_switches=3 [ 179] proc: process 9 exited with code 0 [ 179] sched: switch to 2:1 [ 180] sched: off-stack process stack release pid=9 stacks=1 reason=deferred-process-drop proof_result=ok [ 181] proc: spawned process 10 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 181] proc: process 10 session context profile='system' for request 'cap-slot-leak' [ 181] sched: added process 10 [ 182] sched: switch 2:1 -> 10:1 [ 182] syscall: unknown syscall 0 [ 182] pid=10 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 183] pid=10 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 183] pid=10 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 184] sched: process_cpu_accounting pid=10 runtime_ns=20887315 context_switches=3 [ 184] proc: process 10 exited with code 0 [ 184] sched: switch to 2:1 [ 185] sched: off-stack process stack release pid=10 stacks=1 reason=deferred-process-drop proof_result=ok [ 185] proc: spawned process 11 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 185] proc: process 11 session context profile='system' for request 'cap-slot-leak' [ 185] sched: added process 11 [ 186] 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 [ 187] sched: process_cpu_accounting pid=11 runtime_ns=10313119 context_switches=2 [ 187] proc: process 11 exited with code 0 [ 187] pid=11 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 187] sched: switch to 2:1 [ 188] 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 [ 189] syscall: unknown syscall 0 [ 189] sched: process_cpu_accounting pid=12 runtime_ns=4915729 context_switches=1 [ 189] proc: process 12 exited with code 0 [ 189] pid=12 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 189] sched: switch to 2:1 [ 190] sched: off-stack process stack release pid=12 stacks=1 reason=deferred-process-drop proof_result=ok [ 191] proc: spawned process 13 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 191] proc: process 13 session context profile='system' for request 'cap-slot-leak' [ 191] sched: added process 13 [ 192] sched: switch 2:1 -> 13:1 [ 193] syscall: unknown syscall 0 [ 193] sched: process_cpu_accounting pid=13 runtime_ns=5231581 context_switches=2 [ 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 [ 194] proc: spawned process 14 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 194] proc: process 14 session context profile='system' for request 'cap-slot-leak' [ 194] sched: added process 14 [ 195] sched: switch 2:1 -> 14:1 [ 195] syscall: unknown syscall 0 [ 195] sched: process_cpu_accounting pid=14 runtime_ns=4857899 context_switches=1 [ 195] proc: process 14 exited with code 0 [ 195] pid=14 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 195] sched: switch to 2:1 [ 196] sched: off-stack process stack release pid=14 stacks=1 reason=deferred-process-drop proof_result=ok [ 197] proc: spawned process 15 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 197] proc: process 15 session context profile='system' for request 'cap-slot-leak' [ 197] sched: added process 15 [ 198] sched: switch 2:1 -> 15:1 [ 199] syscall: unknown syscall 0 [ 199] sched: process_cpu_accounting pid=15 runtime_ns=5258734 context_switches=2 [ 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 [ 200] proc: spawned process 16 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 200] proc: process 16 session context profile='system' for request 'cap-slot-leak' [ 200] sched: added process 16 [ 201] sched: switch 2:1 -> 16:1 [ 201] syscall: unknown syscall 0 [ 201] sched: process_cpu_accounting pid=16 runtime_ns=5002045 context_switches=1 [ 201] proc: process 16 exited with code 0 [ 201] pid=16 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 201] sched: switch to 2:1 [ 202] sched: off-stack process stack release pid=16 stacks=1 reason=deferred-process-drop proof_result=ok [ 203] proc: spawned process 17 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 203] proc: process 17 session context profile='system' for request 'cap-slot-leak' [ 203] sched: added process 17 [ 204] sched: switch 2:1 -> 17:1 [ 204] syscall: unknown syscall 0 [ 204] sched: process_cpu_accounting pid=17 runtime_ns=4941949 context_switches=1 [ 204] proc: process 17 exited with code 0 [ 204] pid=17 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 204] sched: switch to 2:1 [ 205] sched: off-stack process stack release pid=17 stacks=1 reason=deferred-process-drop proof_result=ok [ 206] proc: spawned process 18 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 206] proc: process 18 session context profile='system' for request 'cap-slot-leak' [ 206] sched: added process 18 [ 207] sched: switch 2:1 -> 18:1 [ 207] syscall: unknown syscall 0 [ 207] sched: process_cpu_accounting pid=18 runtime_ns=4982012 context_switches=1 [ 207] proc: process 18 exited with code 0 [ 207] pid=18 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 207] sched: switch to 2:1 [ 208] sched: off-stack process stack release pid=18 stacks=1 reason=deferred-process-drop proof_result=ok [ 208] proc: spawned process 19 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 208] proc: process 19 session context profile='system' for request 'cap-slot-leak' [ 208] sched: added process 19 [ 209] sched: switch 2:1 -> 19:1 [ 210] syscall: unknown syscall 0 [ 210] pid=19 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 210] pid=19 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 211] pid=19 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 212] sched: process_cpu_accounting pid=19 runtime_ns=21897724 context_switches=4 [ 212] proc: process 19 exited with code 0 [ 212] sched: switch to 2:1 [ 213] sched: off-stack process stack release pid=19 stacks=1 reason=deferred-process-drop proof_result=ok [ 213] proc: spawned process 20 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 213] proc: process 20 session context profile='system' for request 'cap-slot-leak' [ 213] sched: added process 20 [ 214] sched: switch 2:1 -> 20:1 [ 215] syscall: unknown syscall 0 [ 215] pid=20 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 215] pid=20 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 216] pid=20 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 216] sched: process_cpu_accounting pid=20 runtime_ns=21229080 context_switches=3 [ 216] proc: process 20 exited with code 0 [ 216] sched: switch to 2:1 [ 217] sched: off-stack process stack release pid=20 stacks=1 reason=deferred-process-drop proof_result=ok [ 218] proc: spawned process 21 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 218] proc: process 21 session context profile='system' for request 'cap-slot-leak' [ 218] sched: added process 21 [ 219] sched: switch 2:1 -> 21:1 [ 219] syscall: unknown syscall 0 [ 220] pid=21 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 220] pid=21 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 221] pid=21 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 221] sched: process_cpu_accounting pid=21 runtime_ns=21155326 context_switches=3 [ 221] proc: process 21 exited with code 0 [ 221] sched: switch to 2:1 [ 222] sched: off-stack process stack release pid=21 stacks=1 reason=deferred-process-drop proof_result=ok [ 223] proc: spawned process 22 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 223] proc: process 22 session context profile='system' for request 'cap-slot-leak' [ 223] sched: added process 22 [ 224] sched: switch 2:1 -> 22:1 [ 224] syscall: unknown syscall 0 [ 225] pid=22 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 225] pid=22 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 226] pid=22 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 226] sched: process_cpu_accounting pid=22 runtime_ns=21462499 context_switches=3 [ 226] proc: process 22 exited with code 0 [ 226] sched: switch to 2:1 [ 227] sched: off-stack process stack release pid=22 stacks=1 reason=deferred-process-drop proof_result=ok [ 228] proc: spawned process 23 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 228] proc: process 23 session context profile='system' for request 'cap-slot-leak' [ 228] sched: added process 23 [ 229] sched: switch 2:1 -> 23:1 [ 229] syscall: unknown syscall 0 [ 229] pid=23 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 230] pid=23 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 231] pid=23 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 231] sched: process_cpu_accounting pid=23 runtime_ns=21115099 context_switches=3 [ 231] proc: process 23 exited with code 0 [ 231] sched: switch to 2:1 [ 232] sched: off-stack process stack release pid=23 stacks=1 reason=deferred-process-drop proof_result=ok [ 233] proc: spawned process 24 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 233] proc: process 24 session context profile='system' for request 'cap-slot-leak' [ 233] sched: added process 24 [ 234] sched: switch 2:1 -> 24:1 [ 234] syscall: unknown syscall 0 [ 234] pid=24 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 235] sched: process_cpu_accounting pid=24 runtime_ns=10507159 context_switches=2 [ 235] proc: process 24 exited with code 0 [ 235] pid=24 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 235] sched: switch to 2:1 [ 236] sched: off-stack process stack release pid=24 stacks=1 reason=deferred-process-drop proof_result=ok [ 237] proc: spawned process 25 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 237] proc: process 25 session context profile='system' for request 'cap-slot-leak' [ 237] sched: added process 25 [ 238] sched: switch 2:1 -> 25:1 [ 238] syscall: unknown syscall 0 [ 239] sched: process_cpu_accounting pid=25 runtime_ns=5320799 context_switches=2 [ 239] proc: process 25 exited with code 0 [ 239] pid=25 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 239] sched: switch to 2:1 [ 240] sched: off-stack process stack release pid=25 stacks=1 reason=deferred-process-drop proof_result=ok [ 241] proc: spawned process 26 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 241] proc: process 26 session context profile='system' for request 'cap-slot-leak' [ 241] sched: added process 26 [ 242] sched: switch 2:1 -> 26:1 [ 242] syscall: unknown syscall 0 [ 243] sched: process_cpu_accounting pid=26 runtime_ns=5308432 context_switches=2 [ 243] proc: process 26 exited with code 0 [ 243] pid=26 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 243] sched: switch to 2:1 [ 244] sched: off-stack process stack release pid=26 stacks=1 reason=deferred-process-drop proof_result=ok [ 245] proc: spawned process 27 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 245] proc: process 27 session context profile='system' for request 'cap-slot-leak' [ 245] sched: added process 27 [ 246] sched: switch 2:1 -> 27:1 [ 246] syscall: unknown syscall 0 [ 246] sched: process_cpu_accounting pid=27 runtime_ns=4907346 context_switches=1 [ 246] proc: process 27 exited with code 0 [ 246] pid=27 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 246] sched: switch to 2:1 [ 247] sched: off-stack process stack release pid=27 stacks=1 reason=deferred-process-drop proof_result=ok [ 248] proc: spawned process 28 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 248] proc: process 28 session context profile='system' for request 'cap-slot-leak' [ 248] sched: added process 28 [ 249] sched: switch 2:1 -> 28:1 [ 249] syscall: unknown syscall 0 [ 249] sched: process_cpu_accounting pid=28 runtime_ns=4928052 context_switches=1 [ 249] proc: process 28 exited with code 0 [ 249] pid=28 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 249] sched: switch to 2:1 [ 250] sched: off-stack process stack release pid=28 stacks=1 reason=deferred-process-drop proof_result=ok [ 251] proc: spawned process 29 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 251] proc: process 29 session context profile='system' for request 'cap-slot-leak' [ 251] sched: added process 29 [ 252] sched: switch 2:1 -> 29:1 [ 253] syscall: unknown syscall 0 [ 253] sched: process_cpu_accounting pid=29 runtime_ns=5261635 context_switches=2 [ 253] proc: process 29 exited with code 0 [ 253] pid=29 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 253] sched: switch to 2:1 [ 254] sched: off-stack process stack release pid=29 stacks=1 reason=deferred-process-drop proof_result=ok [ 254] proc: spawned process 30 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 254] proc: process 30 session context profile='system' for request 'cap-slot-leak' [ 254] sched: added process 30 [ 255] sched: switch 2:1 -> 30:1 [ 256] syscall: unknown syscall 0 [ 256] sched: process_cpu_accounting pid=30 runtime_ns=5359675 context_switches=2 [ 256] proc: process 30 exited with code 0 [ 256] pid=30 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 256] sched: switch to 2:1 [ 257] sched: off-stack process stack release pid=30 stacks=1 reason=deferred-process-drop proof_result=ok [ 257] proc: spawned process 31 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 257] proc: process 31 session context profile='system' for request 'cap-slot-leak' [ 257] sched: added process 31 [ 258] sched: switch 2:1 -> 31:1 [ 258] syscall: unknown syscall 0 [ 259] sched: process_cpu_accounting pid=31 runtime_ns=5373319 context_switches=2 [ 259] proc: process 31 exited with code 0 [ 259] pid=31 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 259] sched: switch to 2:1 [ 260] sched: off-stack process stack release pid=31 stacks=1 reason=deferred-process-drop proof_result=ok [ 261] proc: spawned process 32 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 261] proc: process 32 session context profile='system' for request 'cap-slot-leak' [ 261] sched: added process 32 [ 262] sched: switch 2:1 -> 32:1 [ 262] syscall: unknown syscall 0 [ 263] sched: process_cpu_accounting pid=32 runtime_ns=5361547 context_switches=2 [ 263] proc: process 32 exited with code 0 [ 263] pid=32 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 263] sched: switch to 2:1 [ 264] 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 [ 266] sched: process_cpu_accounting pid=33 runtime_ns=4959811 context_switches=1 [ 266] proc: process 33 exited with code 0 [ 266] pid=33 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 266] sched: switch to 2:1 [ 267] sched: off-stack process stack release pid=33 stacks=1 reason=deferred-process-drop proof_result=ok [ 268] proc: spawned process 34 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 268] proc: process 34 session context profile='system' for request 'cap-slot-leak' [ 268] sched: added process 34 [ 269] sched: switch 2:1 -> 34:1 [ 269] syscall: unknown syscall 0 [ 269] sched: process_cpu_accounting pid=34 runtime_ns=4893605 context_switches=1 [ 269] proc: process 34 exited with code 0 [ 269] pid=34 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 269] sched: switch to 2:1 [ 270] sched: off-stack process stack release pid=34 stacks=1 reason=deferred-process-drop proof_result=ok [ 271] proc: spawned process 35 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 271] proc: process 35 session context profile='system' for request 'cap-slot-leak' [ 271] sched: added process 35 [ 272] sched: switch 2:1 -> 35:1 [ 273] syscall: unknown syscall 0 [ 273] sched: process_cpu_accounting pid=35 runtime_ns=5439908 context_switches=2 [ 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 [ 274] proc: spawned process 36 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 274] proc: process 36 session context profile='system' for request 'cap-slot-leak' [ 274] sched: added process 36 [ 275] sched: switch 2:1 -> 36:1 [ 275] syscall: unknown syscall 0 [ 276] pid=36 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 276] 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 [ 277] sched: process_cpu_accounting pid=36 runtime_ns=21007679 context_switches=3 [ 277] proc: process 36 exited with code 0 [ 277] sched: switch to 2:1 [ 278] sched: off-stack process stack release pid=36 stacks=1 reason=deferred-process-drop proof_result=ok [ 279] proc: spawned process 37 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 279] proc: process 37 session context profile='system' for request 'cap-slot-leak' [ 279] sched: added process 37 [ 280] sched: switch 2:1 -> 37:1 [ 280] syscall: unknown syscall 0 [ 280] pid=37 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 281] 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 [ 282] sched: process_cpu_accounting pid=37 runtime_ns=20932527 context_switches=3 [ 282] proc: process 37 exited with code 0 [ 282] sched: switch to 2:1 [ 283] sched: off-stack process stack release pid=37 stacks=1 reason=deferred-process-drop proof_result=ok [ 284] proc: spawned process 38 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 284] proc: process 38 session context profile='system' for request 'cap-slot-leak' [ 284] sched: added process 38 [ 285] sched: switch 2:1 -> 38:1 [ 285] syscall: unknown syscall 0 [ 285] pid=38 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 286] pid=38 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 286] pid=38 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 287] sched: process_cpu_accounting pid=38 runtime_ns=21099197 context_switches=3 [ 287] proc: process 38 exited with code 0 [ 287] sched: switch to 2:1 [ 288] sched: off-stack process stack release pid=38 stacks=1 reason=deferred-process-drop proof_result=ok [ 289] proc: spawned process 39 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 289] proc: process 39 session context profile='system' for request 'cap-slot-leak' [ 289] sched: added process 39 [ 290] sched: switch 2:1 -> 39:1 [ 290] syscall: unknown syscall 0 [ 290] pid=39 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 291] pid=39 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 291] pid=39 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 292] sched: process_cpu_accounting pid=39 runtime_ns=20906699 context_switches=3 [ 292] proc: process 39 exited with code 0 [ 292] sched: switch to 2:1 [ 293] sched: off-stack process stack release pid=39 stacks=1 reason=deferred-process-drop proof_result=ok [ 293] proc: spawned process 40 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 293] proc: process 40 session context profile='system' for request 'cap-slot-leak' [ 293] sched: added process 40 [ 294] sched: switch 2:1 -> 40:1 [ 295] syscall: unknown syscall 0 [ 295] pid=40 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 295] pid=40 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 296] pid=40 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 296] sched: process_cpu_accounting pid=40 runtime_ns=20929218 context_switches=3 [ 296] proc: process 40 exited with code 0 [ 296] sched: switch to 2:1 [ 297] sched: off-stack process stack release pid=40 stacks=1 reason=deferred-process-drop proof_result=ok [ 298] proc: spawned process 41 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 298] proc: process 41 session context profile='system' for request 'cap-slot-leak' [ 298] sched: added process 41 [ 299] sched: switch 2:1 -> 41:1 [ 299] syscall: unknown syscall 0 [ 300] pid=41 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 300] sched: process_cpu_accounting pid=41 runtime_ns=10424893 context_switches=2 [ 300] proc: process 41 exited with code 0 [ 300] pid=41 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 300] sched: switch to 2:1 [ 301] sched: off-stack process stack release pid=41 stacks=1 reason=deferred-process-drop proof_result=ok [ 301] proc: spawned process 42 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 301] proc: process 42 session context profile='system' for request 'cap-slot-leak' [ 301] sched: added process 42 [ 302] sched: switch 2:1 -> 42:1 [ 303] syscall: unknown syscall 0 [ 303] sched: process_cpu_accounting pid=42 runtime_ns=5265596 context_switches=2 [ 303] proc: process 42 exited with code 0 [ 303] pid=42 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 303] sched: switch to 2:1 [ 304] sched: off-stack process stack release pid=42 stacks=1 reason=deferred-process-drop proof_result=ok [ 305] proc: spawned process 43 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 305] proc: process 43 session context profile='system' for request 'cap-slot-leak' [ 305] sched: added process 43 [ 306] sched: switch 2:1 -> 43:1 [ 306] syscall: unknown syscall 0 [ 307] sched: process_cpu_accounting pid=43 runtime_ns=5279019 context_switches=2 [ 307] proc: process 43 exited with code 0 [ 307] pid=43 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 307] sched: switch to 2:1 [ 308] sched: off-stack process stack release pid=43 stacks=1 reason=deferred-process-drop proof_result=ok [ 309] proc: spawned process 44 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 309] proc: process 44 session context profile='system' for request 'cap-slot-leak' [ 309] sched: added process 44 [ 310] sched: switch 2:1 -> 44:1 [ 310] syscall: unknown syscall 0 [ 310] sched: process_cpu_accounting pid=44 runtime_ns=4896442 context_switches=1 [ 310] proc: process 44 exited with code 0 [ 310] pid=44 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 310] sched: switch to 2:1 [ 311] sched: off-stack process stack release pid=44 stacks=1 reason=deferred-process-drop proof_result=ok [ 312] proc: spawned process 45 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 312] proc: process 45 session context profile='system' for request 'cap-slot-leak' [ 312] sched: added process 45 [ 313] sched: switch 2:1 -> 45:1 [ 313] syscall: unknown syscall 0 [ 313] sched: process_cpu_accounting pid=45 runtime_ns=4970365 context_switches=1 [ 313] proc: process 45 exited with code 0 [ 313] pid=45 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 313] sched: switch to 2:1 [ 314] sched: off-stack process stack release pid=45 stacks=1 reason=deferred-process-drop proof_result=ok [ 315] proc: spawned process 46 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 315] proc: process 46 session context profile='system' for request 'cap-slot-leak' [ 315] sched: added process 46 [ 316] sched: switch 2:1 -> 46:1 [ 317] syscall: unknown syscall 0 [ 317] sched: process_cpu_accounting pid=46 runtime_ns=5326880 context_switches=2 [ 317] proc: process 46 exited with code 0 [ 317] pid=46 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 317] sched: switch to 2:1 [ 318] sched: off-stack process stack release pid=46 stacks=1 reason=deferred-process-drop proof_result=ok [ 319] proc: spawned process 47 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 319] proc: process 47 session context profile='system' for request 'cap-slot-leak' [ 319] sched: added process 47 [ 320] sched: switch 2:1 -> 47:1 [ 320] syscall: unknown syscall 0 [ 320] sched: process_cpu_accounting pid=47 runtime_ns=4989247 context_switches=1 [ 320] proc: process 47 exited with code 0 [ 320] pid=47 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 320] sched: switch to 2:1 [ 321] sched: off-stack process stack release pid=47 stacks=1 reason=deferred-process-drop proof_result=ok [ 322] proc: spawned process 48 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 322] proc: process 48 session context profile='system' for request 'cap-slot-leak' [ 322] sched: added process 48 [ 323] sched: switch 2:1 -> 48:1 [ 324] syscall: unknown syscall 0 [ 324] sched: process_cpu_accounting pid=48 runtime_ns=5410301 context_switches=2 [ 324] proc: process 48 exited with code 0 [ 324] pid=48 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 324] sched: switch to 2:1 [ 325] sched: off-stack process stack release pid=48 stacks=1 reason=deferred-process-drop proof_result=ok [ 325] proc: spawned process 49 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 325] proc: process 49 session context profile='system' for request 'cap-slot-leak' [ 325] sched: added process 49 [ 326] sched: switch 2:1 -> 49:1 [ 327] syscall: unknown syscall 0 [ 327] sched: process_cpu_accounting pid=49 runtime_ns=5275871 context_switches=2 [ 327] proc: process 49 exited with code 0 [ 327] pid=49 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 327] sched: switch to 2:1 [ 328] sched: off-stack process stack release pid=49 stacks=1 reason=deferred-process-drop proof_result=ok [ 328] proc: spawned process 50 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 328] proc: process 50 session context profile='system' for request 'cap-slot-leak' [ 328] sched: added process 50 [ 329] sched: switch 2:1 -> 50:1 [ 329] syscall: unknown syscall 0 [ 330] sched: process_cpu_accounting pid=50 runtime_ns=5349482 context_switches=2 [ 330] proc: process 50 exited with code 0 [ 330] pid=50 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 330] sched: switch to 2:1 [ 331] sched: off-stack process stack release pid=50 stacks=1 reason=deferred-process-drop proof_result=ok [ 332] proc: spawned process 51 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 332] proc: process 51 session context profile='system' for request 'cap-slot-leak' [ 332] sched: added process 51 [ 333] sched: switch 2:1 -> 51:1 [ 333] syscall: unknown syscall 0 [ 333] sched: process_cpu_accounting pid=51 runtime_ns=4886178 context_switches=1 [ 333] proc: process 51 exited with code 0 [ 333] pid=51 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 333] sched: switch to 2:1 [ 334] sched: off-stack process stack release pid=51 stacks=1 reason=deferred-process-drop proof_result=ok [ 335] proc: spawned process 52 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 335] proc: process 52 session context profile='system' for request 'cap-slot-leak' [ 335] sched: added process 52 [ 336] sched: switch 2:1 -> 52:1 [ 336] syscall: unknown syscall 0 [ 336] sched: process_cpu_accounting pid=52 runtime_ns=4887121 context_switches=1 [ 336] proc: process 52 exited with code 0 [ 336] pid=52 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 336] sched: switch to 2:1 [ 337] sched: off-stack process stack release pid=52 stacks=1 reason=deferred-process-drop proof_result=ok [ 338] proc: spawned process 53 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 338] proc: process 53 session context profile='system' for request 'cap-slot-leak' [ 338] sched: added process 53 [ 339] sched: switch 2:1 -> 53:1 [ 340] syscall: unknown syscall 0 [ 340] 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 [ 341] 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=21485650 context_switches=4 [ 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 [ 343] proc: spawned process 54 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 343] proc: process 54 session context profile='system' for request 'cap-slot-leak' [ 343] sched: added process 54 [ 344] sched: switch 2:1 -> 54:1 [ 345] syscall: unknown syscall 0 [ 345] pid=54 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 345] 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 [ 346] sched: process_cpu_accounting pid=54 runtime_ns=20754954 context_switches=3 [ 346] proc: process 54 exited with code 0 [ 346] sched: switch to 2:1 [ 347] sched: off-stack process stack release pid=54 stacks=1 reason=deferred-process-drop proof_result=ok [ 348] proc: spawned process 55 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 348] proc: process 55 session context profile='system' for request 'cap-slot-leak' [ 348] sched: added process 55 [ 349] sched: switch 2:1 -> 55:1 [ 349] syscall: unknown syscall 0 [ 350] pid=55 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 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] sched: process_cpu_accounting pid=55 runtime_ns=20815968 context_switches=3 [ 351] proc: process 55 exited with code 0 [ 351] sched: switch to 2:1 [ 352] sched: off-stack process stack release pid=55 stacks=1 reason=deferred-process-drop proof_result=ok [ 353] proc: spawned process 56 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 353] proc: process 56 session context profile='system' for request 'cap-slot-leak' [ 353] sched: added process 56 [ 354] sched: switch 2:1 -> 56:1 [ 354] syscall: unknown syscall 0 [ 354] pid=56 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 355] pid=56 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 355] pid=56 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 356] sched: process_cpu_accounting pid=56 runtime_ns=20890358 context_switches=3 [ 356] proc: process 56 exited with code 0 [ 356] sched: switch to 2:1 [ 357] 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 [ 359] 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 [ 360] 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=20868824 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 [ 362] proc: spawned process 58 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 362] proc: process 58 session context profile='system' for request 'cap-slot-leak' [ 362] sched: added process 58 [ 363] sched: switch 2:1 -> 58:1 [ 363] syscall: unknown syscall 0 [ 364] pid=58 invalid submission err=-4 opcode=0 cap_id_bucket=0 cause=cap-not-found [ 364] sched: process_cpu_accounting pid=58 runtime_ns=10258060 context_switches=2 [ 364] proc: process 58 exited with code 0 [ 364] pid=58 invalid submissions suppressed=2 last_err=-4 last_cause=cap-not-found reason=process-exit [ 364] sched: switch to 2:1 [ 365] 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 [ 367] sched: process_cpu_accounting pid=59 runtime_ns=4886535 context_switches=1 [ 367] proc: process 59 exited with code 0 [ 367] pid=59 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 367] sched: switch to 2:1 [ 368] sched: off-stack process stack release pid=59 stacks=1 reason=deferred-process-drop proof_result=ok [ 368] proc: spawned process 60 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 368] proc: process 60 session context profile='system' for request 'cap-slot-leak' [ 368] sched: added process 60 [ 369] sched: switch 2:1 -> 60:1 [ 370] syscall: unknown syscall 0 [ 370] sched: process_cpu_accounting pid=60 runtime_ns=5286166 context_switches=2 [ 370] proc: process 60 exited with code 0 [ 370] pid=60 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 370] sched: switch to 2:1 [ 371] sched: off-stack process stack release pid=60 stacks=1 reason=deferred-process-drop proof_result=ok [ 372] proc: spawned process 61 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 372] proc: process 61 session context profile='system' for request 'cap-slot-leak' [ 372] sched: added process 61 [ 373] sched: switch 2:1 -> 61:1 [ 373] syscall: unknown syscall 0 [ 374] sched: process_cpu_accounting pid=61 runtime_ns=5315696 context_switches=2 [ 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 [ 376] proc: spawned process 62 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 376] proc: process 62 session context profile='system' for request 'cap-slot-leak' [ 376] sched: added process 62 [ 377] sched: switch 2:1 -> 62:1 [ 377] syscall: unknown syscall 0 [ 377] sched: process_cpu_accounting pid=62 runtime_ns=4818214 context_switches=1 [ 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=4852949 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 [ 381] proc: spawned process 64 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 381] proc: process 64 session context profile='system' for request 'cap-slot-leak' [ 381] sched: added process 64 [ 382] sched: switch 2:1 -> 64:1 [ 382] syscall: unknown syscall 0 [ 383] sched: process_cpu_accounting pid=64 runtime_ns=5289209 context_switches=2 [ 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 [ 385] proc: spawned process 65 for request 'cap-slot-leak' (binary='unprivileged-stranger', 0 caps) [ 385] proc: process 65 session context profile='system' for request 'cap-slot-leak' [ 385] sched: added process 65 [ 386] sched: switch 2:1 -> 65:1 [ 386] syscall: unknown syscall 0 [ 387] sched: process_cpu_accounting pid=65 runtime_ns=5233302 context_switches=2 [ 387] proc: process 65 exited with code 0 [ 387] pid=65 invalid submissions suppressed=3 last_err=-4 last_cause=cap-not-found reason=process-exit [ 387] sched: switch to 2:1 [ 388] 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=2767596532 context_switches=175 [ 389] proc: process 2 exited with code 0 [ 389] sched: last process exited, halting measure: ring dispatches=882 sqes=1409 nop_sqes=130 measure: serial bytes=84202 measure: timer interrupts=389 user_scheduler=364 kernel_only=25 bsp_tick_advances=389 measure: tlb cr3_writes=396 pending_flush_checks=2590 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=1417 cycles=7536016333 avg=5318289 measure: segment sqe_validation count=2267 cycles=44751911 avg=19740 measure: segment cap_lookup count=1680 cycles=981957125 avg=584498 measure: segment capnp_decode_encode count=643 cycles=4519926 avg=7029 measure: segment method_body count=756 cycles=6361492178 avg=8414672 measure: segment null_cap_empty count=80 cycles=4879 avg=60 measure: segment null_cap_schema count=80 cycles=491280 avg=6141 measure: schema_dispatch_overhead null_empty_count=80 null_empty_avg=60 null_schema_count=80 null_schema_avg=6141 overhead_cycles=6081 measure: cap_slot_memory slot_bytes=80 max_slots=256 max_slot_storage_bytes=20480 measure: segment cqe_post count=1397 cycles=785169 avg=562 measure: segment waiter_wake_check count=1384 cycles=16272402 avg=11757 measure: segment sched_choose_next count=636 cycles=53944022 avg=84817 measure: direct_ipc_ready_to_select count=71 cycles=28565880 avg=402336 min=382935 max=548506 measure: segment schedule_save_requeue count=364 cycles=64862044 avg=178192 measure: segment timer_sleep_wake count=1026 cycles=261277 avg=254 measure: segment park_wake count=1026 cycles=7404970 avg=7217 measure: segment cap_wait_wake_scan count=1038 cycles=2742684 avg=2642 measure: segment thread_exit_join_cleanup count=87 cycles=200153704 avg=2300617 measure: segment process_exit_cleanup count=64 cycles=721026857 avg=11266044 measure: segment process_drop count=63 cycles=1166378384 avg=18513942 measure: scheduler choose_direct_candidates=71 choose_run_queue_candidates=538 choose_idle_candidates=9 choose_runnable=618 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=12031 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=4930812496 measure: scheduler_lock_site schedule_pre_ring acquisitions=1144 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=221423173 measure: scheduler_lock_site schedule_select acquisitions=364 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=934146539 measure: scheduler_lock_site block_current acquisitions=3517 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=1351795016 measure: scheduler_lock_site wake_unblock acquisitions=1419 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=17841126 measure: scheduler_lock_site metadata acquisitions=5087 contended=0 spin_loops=0 wait_cycles=0 hold_cycles=506077498 measure: shared_kernel_lock frame_alloc acquisitions=53618 contended=0 spin_loops=0 measure: shared_kernel_lock frame_free acquisitions=52794 contended=0 spin_loops=0 measure: shared_kernel_lock ring_dispatch_cap_table acquisitions=364 contended=0 spin_loops=0 measure: shared_kernel_lock ring_dispatch_scratch acquisitions=364 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=3849 contended=0 spin_loops=0 measure: shared_kernel_lock heap acquisitions=5453 contended=0 spin_loops=0 measure: user_pc_samples total=0 overflow=0