{"action":"opened","issue":{"url":"https://api.github.com/repos/thepowersgang/rust_os/issues/3","labels_url":"https://api.github.com/repos/thepowersgang/rust_os/issues/3/labels{/name}","comments_url":"https://api.github.com/repos/thepowersgang/rust_os/issues/3/comments","events_url":"https://api.github.com/repos/thepowersgang/rust_os/issues/3/events","html_url":"https://github.com/thepowersgang/rust_os/issues/3","id":111961675,"number":3,"title":"'make run' is ok, but report a error: \"Unable to open initial userland loader '/system/Tifflin/bin/loader'\"","user":{"login":"Fishman2009","id":15076000,"avatar_url":"https://avatars.githubusercontent.com/u/15076000?v=3","gravatar_id":"","url":"https://api.github.com/users/Fishman2009","html_url":"https://github.com/Fishman2009","followers_url":"https://api.github.com/users/Fishman2009/followers","following_url":"https://api.github.com/users/Fishman2009/following{/other_user}","gists_url":"https://api.github.com/users/Fishman2009/gists{/gist_id}","starred_url":"https://api.github.com/users/Fishman2009/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/Fishman2009/subscriptions","organizations_url":"https://api.github.com/users/Fishman2009/orgs","repos_url":"https://api.github.com/users/Fishman2009/repos","events_url":"https://api.github.com/users/Fishman2009/events{/privacy}","received_events_url":"https://api.github.com/users/Fishman2009/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"milestone":null,"comments":0,"created_at":"2015-10-17T12:39:57Z","updated_at":"2015-10-17T12:39:57Z","closed_at":null,"body":"good job! I build successfully from clean checkout, and run it!\r\nbut maybe has error!\r\n\r\n\"Unable to open initial userland loader '/system/Tifflin/bin/loader'\"\r\n\r\nI installed some package before run 'make run' !\r\n$apt-get install pxe-kexec  mtools\r\n\r\n$make run\r\n···\r\n>>> all: libcore source\r\nMakefile:36: start.ao syscalls.o virtio.o storage_ata.o input_ps2.o fs_fat.o fs_iso9660.o libkernel.rlib libcore.rlib  gui.o stack_dst.o      storage_scsi.o   gui.o   utf16.o blockcache.o    \r\nmake[1]: `../libcore/lib.rs' is up to date.\r\n>>> all: Graphics\r\nmake[1]: Entering directory `/home/fishman/rust_os/Graphics'\r\nmake[1]: Nothing to be done for `all'.\r\nmake[1]: Leaving directory `/home/fishman/rust_os/Graphics'\r\n>>> all: Usermode\r\nmake[1]: Nothing to be done for `all'.\r\n>>> all: Kernel\r\nMakefile:36: start.ao syscalls.o virtio.o storage_ata.o input_ps2.o fs_fat.o fs_iso9660.o libkernel.rlib libcore.rlib  gui.o stack_dst.o      storage_scsi.o   gui.o   utf16.o blockcache.o    \r\nmake -C Kernel/rundir run\r\nmake[1]: Entering directory `/home/fishman/rust_os/Kernel/rundir'\r\n./RunQemuPXE ../bin/kernel-amd64.bin.elf32 \"SYSROOT=Tifflin SYSDISK=ATA-0p0\" \"-s -no-reboot -nographic -d int,guest_errors -D qemu_int_log.txt -net nic -drive if=ide,index=0,file=DiskImages/amd64/hda.img,format=raw\" | tee QemuLog.txt\r\nOK43e6H\r\n     0n 0[kernel] - Tifflin Kernel v0.1 build ubuntu:1 starting\r\n     0n 0[kernel] - > Git state : ada76408078a5291fc3fc1429b6e08ef4220bc8e, Built with rustc 1.5.0-nightly (6cdf31b12 2015-10-15)\r\n     0d 0[kernel::arch::imp::boot] - loader_ptr = 0xffffffff80002134\r\n     0n 0[kernel::arch::imp::boot] - Loading multiboot from loader 'PXELINUX 4.05 20140113 ' (flags = 0xe65)\r\n     0t 0[kernel::arch::imp::boot] - L327: MultibootInfo::_vidmode: info = VbeModeInfo { attributes: 187, window_attrs: [7, 0], granuality: 16, window_size: 64, window_segments: [40960, 0], win_pos_fcn_fptr: [19334, 49152], pitch: 2560, x_res: 1280, y_res: 1024, char_w: 8, char_h: 16, n_planes: 1, bpp: 16, n_banks: 1, memory_model: 6, bank_size: 0, n_pages: 1, _resvd: 1, red_mask: 5, red_position: 11, green_mask: 6, green_position: 5, blue_mask: 5, blue_position: 0, rsv_mask: 0, rsv_position: 0, directcolor_attributes: 0, physbase: 4227858432, offscreen_ptr: 0, offscreen_size_kb: 0, lfb_pitch: 2560, image_count_banked: 0, image_count_lfb: 0 }\r\n     0d 0[kernel::arch::imp::boot] - Symbols ELF - num=11, size=40, addr=0x219000, shndx=8\r\n     0t 0[kernel::memory::virt] - L277: 0x219000+1pg is 0xffffffff80219000\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 0, sh_type: 0, sh_flags: 0, sh_addr: 0, sh_offset: 0, sh_size: 0, sh_link: 0, sh_info: 0, sh_addralign: 0, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 27, sh_type: 1, sh_flags: 2, sh_addr: 1048864, sh_offset: 288, sh_size: 2656, sh_link: 0, sh_info: 0, sh_addralign: 1, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 33, sh_type: 1, sh_flags: 6, sh_addr: 2148536320, sh_offset: 4096, sh_size: 792327, sh_link: 0, sh_info: 0, sh_addralign: 16, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 39, sh_type: 1, sh_flags: 2, sh_addr: 2149330944, sh_offset: 798720, sh_size: 242544, sh_link: 0, sh_info: 0, sh_addralign: 32, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 47, sh_type: 1, sh_flags: 3, sh_addr: 2149576704, sh_offset: 1044480, sh_size: 97776, sh_link: 0, sh_info: 0, sh_addralign: 32, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 53, sh_type: 1, sh_flags: 3, sh_addr: 2149674480, sh_offset: 1142256, sh_size: 40, sh_link: 0, sh_info: 0, sh_addralign: 8, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 68, sh_type: 8, sh_flags: 3, sh_addr: 2149674528, sh_offset: 1142296, sh_size: 8296, sh_link: 0, sh_info: 0, sh_addralign: 32, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 73, sh_type: 1, sh_flags: 48, sh_addr: 2199992, sh_offset: 1142296, sh_size: 17, sh_link: 0, sh_info: 0, sh_addralign: 1, sh_entsize: 1 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 17, sh_type: 3, sh_flags: 0, sh_addr: 2200009, sh_offset: 1142313, sh_size: 82, sh_link: 0, sh_info: 0, sh_addralign: 1, sh_entsize: 0 }\r\n     0t 0[kernel::arch::imp::boot] - L263: shent = ShEnt { sh_name: 1, sh_type: 2, sh_flags: 0, sh_addr: 2200092, sh_offset: 1142396, sh_size: 107808, sh_link: 10, sh_info: 5243, sh_addralign: 4, sh_entsize: 16 }\r\n     0t 0[kernel::memory::virt] - L277: 0x21921c+27pg is 0xffffffff80219000\r\n     0t 0[kernel::memory::virt] - L277: 0x23373c+46pg is 0xffffffff80233000\r\n     0d 0[kernel::arch::imp::boot] - info = {..., .lomem=625, .himem=129124 }\r\n     0d 0[kernel::memory::memorymap] - set_range(base=0x100000, size=0x209000, state=Used, domain=0)\r\n     0d 0[kernel::memory::memorymap] - 1: (0x100000+0x209000): slot=0x100000+0x7e19000 Free Domain 0\r\n     0d 0[kernel::memory::memorymap] - set_range(base=0x2058, size=0x36, state=Used, domain=0)\r\n     0d 0[kernel::memory::memorymap] - 0: (0x2000+0x1000): slot=0x1000+0x9b400 Free Domain 0\r\n     0d 0[kernel::memory::memorymap] - 1: (0x2000+0x1000): slot=0x2000+0x9a400 Free Domain 0\r\n     0d 0[kernel::memory::memorymap] - set_range(base=0x21921c, size=0x1a520, state=Used, domain=0)\r\n     0d 0[kernel::memory::memorymap] - 3: (0x219000+0x1b000): slot=0x100000+0x209000 Used Domain 0\r\n     0d 0[kernel::memory::memorymap] - set_range(base=0x23373c, size=0x2ccf2, state=Used, domain=0)\r\n     0d 0[kernel::memory::memorymap] - 3: (0x233000+0x2e000): slot=0x100000+0x209000 Used Domain 0\r\n     0l 0[kernel::memory::phys] - Memory Map:\r\n     0l 0[kernel::memory::phys] - #0 : 0x1000+0x1000 Used Domain 0\r\n     0l 0[kernel::memory::phys] - #1 : 0x2000+0x1000 Used Domain 0\r\n     0l 0[kernel::memory::phys] - #2 : 0x3000+0x99400 Free Domain 0\r\n     0l 0[kernel::memory::phys] - #3 : 0x100000+0x209000 Used Domain 0\r\n     0l 0[kernel::memory::phys] - #4 : 0x309000+0x7c10000 Free Domain 0\r\n     0t 0[kernel::arch::imp::threads] - L72: State::new(AddressSpace(AddressSpace(0x1ff000)))\r\n     0d 0[kernel::threads::thread] - Creating thread 0xffff800000000180(0 ThreadZero)\r\n     0d 0[kernel::arch::imp::threads] - init_tid0_state - cr3 = 0x1ff000\r\n     0l 0[kernel] - Command line = 'kernel-amd64.bin.elf32 SYSROOT=Tifflin SYSDISK=ATA-0p0'\r\n     0w 0[kernel::config] - Unknown option 'kernel-amd64.bin.elf32\r\n     0d 0[kernel] - Video mode : 1280x1024 @ 0xfc000000\r\n     0t 0[kernel::arch::imp::threads] - L72: State::new(AddressSpace(AddressSpace(0x1ff000)))\r\n     0d 0[kernel::threads::thread] - Creating thread 0xffff8000000002e0(1 IRQ Worker)\r\n     0t 0[kernel::arch::imp::threads] - L129: prep_tls(0xffffa00000018000,0xffffa00000011000,0xffff8000000002e0)\r\n     0d 0[kernel::threads] - Yielding CPU to 0xffff8000000002e0(1 IRQ Worker)\r\n     0t 0[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa00000017f88,CR3=0x1ff000,TLS=0xffffa00000017fd0\r\n     0d 1[kernel::threads] - Task switch to 0xffff800000000180(0 ThreadZero)\r\n     0t 1[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000000fbc8,CR3=0x1ff000,TLS=0xffffa0000000ffd0\r\n     0d 0[kernel::modules] - baseptr=0xffffffff801fdf30, size=0x440, count=17\r\n     0d 0[kernel::modules] - s_modules=0xffffffff801fdf30+0x11\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fdf30 0xffffffff801ccbab 'VirtIO'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fdf70 0xffffffff801cd758 'ATA'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fdfb0 0xffffffff801cf4b8 'PS2'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fdff0 0xffffffff801d0bb0 'FS_FAT'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe030 0xffffffff801d1f88 'FS_ISO9660'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe070 0xffffffff801d8490 'Video'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe0b0 0xffffffff801d9108 'Storage'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe0f0 0xffffffff801d99a0 'DeviceManager'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe130 0xffffffff801d9e48 'VFS'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe170 0xffffffff801db789 'PCI'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe1b0 0xffffffff801dc040 'MapperMBR'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe1f0 0xffffffff801d99c0 'arch'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe230 0xffffffff801de560 'TSS'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe270 0xffffffff801dc3e8 'APIC'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe2b0 0xffffffff801dc3ec 'HPET'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe2f0 0xffffffff801de580 'ACPI'\r\n     0d 0[kernel::modules] - mod = 0xffffffff801fe330 0xffffffff801e24d8 'GUI'\r\n     0d 0[kernel::modules] - #16: GUI Deps\r\n     0d 0[kernel::modules] - #5: Video Deps\r\n     0d 0[kernel::modules] - #5: Video Init\r\n     0n 0[kernel::metadevs::video] - Using boot video mode VideoMode { 1280x1024 R5G6B5 2560b @ 0xfc000000 }\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0xfc000000 => 0xffffb00000000000\r\n     0d 0[kernel::modules] - #16: GUI Init\r\n     0t 0[kernel::arch::imp::threads] - L72: State::new(AddressSpace(AddressSpace(0x1ff000)))\r\n     0d 0[kernel::threads::thread] - Creating thread 0xffff800000000800(2 GUI Compositor)\r\n     0t 0[kernel::arch::imp::threads] - L129: prep_tls(0xffffa00000020000,0xffffa00000019000,0xffff800000000800)\r\n     0d 0[kernel::threads] - Yielding CPU to 0xffff800000000800(2 GUI Compositor)\r\n     0t 0[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000001ff88,CR3=0x1ff000,TLS=0xffffa0000001ffd0\r\n     0d 2[gui::windows] - GUI Render Thread started\r\n     0t 2[kernel::threads::wait_queue] - L32: WaitQueue::wait(...)\r\n     0d 2[kernel::threads] - Task switch to 0xffff800000000180(0 ThreadZero)\r\n     0t 2[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000000f0e8,CR3=0x1ff000,TLS=0xffffa0000000ffd0\r\n     0t 0[gui::windows::winbuf] - L56: WinBuf::resize(1280x1024) px_count = 1310720\r\n     0t 0[gui::windows::winbuf] - L56: WinBuf::resize(128x128) px_count = 16384\r\n     0t 0[gui::windows::window] - L213: Window::blit_rect((0,0 + 128x128), data=16384px)\r\n     0t 0[kernel::threads::wait_queue] - L76: WaitQueue::wake_one()\r\n     0d 0[kernel::modules] - #0: VirtIO Deps\r\n     0d 0[kernel::modules] - #7: DeviceManager Deps\r\n     0d 0[kernel::modules] - #11: arch Deps\r\n     0d 0[kernel::modules] - #13: APIC Deps\r\n     0d 0[kernel::modules] - #15: ACPI Deps\r\n     0d 0[kernel::modules] - #15: ACPI Init\r\n     0t 0[kernel::arch::imp::acpi::internal] - L33: AcpiInitializeSubsystem\r\n     0t 0[kernel::arch::imp::acpi::internal] - L35: AcpiInitializeTables\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x40e, 2)\r\n     0t 0[kernel::memory::virt] - L395: map_hw: Fixed allocation 0x0 => 0xffffffff80000000\r\n     0t 0[kernel::memory::virt] - L250: unmap(*0xffffffff80000000 1) - Fixed alloc\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x9fc00, 1024)\r\n     0t 0[kernel::memory::virt] - L395: map_hw: Fixed allocation 0x9f000 => 0xffffffff8009f000\r\n     0t 0[kernel::memory::virt] - L250: unmap(*0xffffffff8009f000 1) - Fixed alloc\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0xe0000, 131072)\r\n     0t 0[kernel::memory::virt] - L395: map_hw: Fixed allocation 0xe0000 => 0xffffffff800e0000\r\n     0t 0[kernel::memory::virt] - L250: unmap(*0xffffffff800e0000 32) - Fixed alloc\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0xf09e0, 36)\r\n     0t 0[kernel::memory::virt] - L395: map_hw: Fixed allocation 0xf0000 => 0xffffffff800f0000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: RSDP 0x0F09E0 14 (v0 BOCHS )\r\n     0t 0[kernel::memory::virt] - L250: unmap(*0xffffffff800f0000 1) - Fixed alloc\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffbc1, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000280000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: RSDT 0x07FFFBC1 34 (v1 BOCHS  BXPCRSDT 1 BXPC 1)\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000280000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffbc1, 52)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000280000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff1c0, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff1c0, 116)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: FACP 0x07FFF1C0 74 (v1 BOCHS  BXPCFACP 1 BXPC 1)\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff1c0, 116)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7ffe040, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7ffe000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7ffe000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7ffe040, 4480)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7ffe000 => 0xffffb00000281000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: DSDT 0x07FFE040 1180 (v1 BOCHS  BXPCDSDT 1 BXPC 1)\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 2) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7ffe000\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7ffe000, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7ffe000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7ffe000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7ffe000, 64)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7ffe000 => 0xffffb00000281000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: FACS 0x07FFE000 40\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7ffe000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff234, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff234, 2269)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: SSDT 0x07FFF234 8DD (v1 BOCHS  BXPCSSDT 1 BXPC 1)\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffb11, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffb11, 120)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: APIC 0x07FFFB11 78 (v1 BOCHS  BXPCAPIC 1 BXPC 1)\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffb89, 36)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffb89, 56)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000281000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: HPET 0x07FFFB89 38 (v1 BOCHS  BXPCHPET 1 BXPC 1)\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000281000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::memory::virt] - L254: unmap(*0xffffb00000280000 1) - Dynamic alloc\r\n     0l 0[kernel::memory::phys] - Calling deref_frame on non-RAM 0x7fff000\r\n     0t 0[kernel::arch::imp::acpi::internal] - L37: AcpiLoadTables\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7ffe040, 4480)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7ffe000 => 0xffffb00000280000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - Table [DSDT] (id 1) - 144 Objects with 18 Devices, 8 Regions, 52 Methods (6/46/5 Serial/Non/Cvt)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff234, 2269)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000282000\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - Table [SSDT] (id 2) - 155 Objects with 32 Devices, 1 Regions, 42 Methods (0/42/0 Serial/Non/Cvt)\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - ACPI: All ACPI Tables successfully acquired\r\n     0t 0[kernel::arch::imp::acpi::internal] - L39: AcpiEnableSubsystem\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb004, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb004, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb2, Value=0xf1, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb004, Width=16) = 0x1\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - evxfevnt-109 Enableevxfevnt: Transition to ACPI mode successful\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7ffe000, 64)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7ffe000 => 0xffffb00000283000\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb002, Value=0x0, Width=16)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb002, Value=0x0, Width=16)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb002, Value=0x0, Width=16)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb002, Value=0x0, Width=16)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb002, Value=0x0, Width=16)\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] - Initializing General Purpose Events (GPEs):\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe0, Value=0xff, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe1, Value=0xff, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe2, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe2, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xafe3, Width=8) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xafe3, Value=0x0, Width=8)\r\n     0d 0[kernel::arch::imp::acpi::internal::shim_out] -     Initialized GPE 0 to F [_GPE] 2 regs on interrupt 0x9 (SCI)\r\n     0w 0[kernel::arch::imp::acpi::internal::shim_out] - TODO: AcpiOsInstallInterruptHandler(InterruptLevel=9, Handler=0xffffffff80107c20, Context=0xffff800000801ac0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x0\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L298: AcpiOsWritePort(0xb002, Value=0x20, Width=16)\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L291: AcpiOsReadPort(0xb002, Width=16) = 0x20\r\n     0d 0[kernel::modules] - #13: APIC Init\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffb11, 120)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000284000\r\n     0d 0[kernel::arch::imp::acpi::internal] - AcpiGetTable: out_ptr = 0xffffb00000284b11\r\n     0d 0[kernel::logging] - AcpiGetTable Dump 0xffffb00000284b11+36\r\n     0d 0[kernel::logging] - AcpiGetTable 0xffffb00000284b11: 41 50 49 43 78 00 00 00  01 ed 42 4f 43 48 53 20 |APICx.....BOCHS |\r\n     0d 0[kernel::logging] - AcpiGetTable 0xffffb00000284b21: 42 58 50 43 41 50 49 43  01 00 00 00 42 58 50 43 |BXPCAPIC....BXPC|\r\n     0d 0[kernel::logging] - AcpiGetTable 0xffffb00000284b31: 01 00 00 00                                      |....            |\r\n     0n 0[kernel::arch::imp::acpi] - SDT size mismatch 120 != sizeof(arch::imp::acpi::SDT<arch::imp::hw::apic::init::ACPI_MADT>) 44\r\n     0d 0[kernel::arch::imp::hw::apic::init] - MADT = {\r\n     0d 0[kernel::arch::imp::hw::apic::init] -   local_controller_addr: 0xfee00000\r\n     0d 0[kernel::arch::imp::hw::apic::init] -   flags: 0x1\r\n     0d 0[kernel::arch::imp::hw::apic::init] - }\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @0: DevLAPIC({Proc:0,APIC:0,Flags:0x1})\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @1: DevIOAPIC({ID:0,Addr:0xfec00000,BaseIRQ:0})\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @2: DevIntSrcOvr(MADT_IntSrcOvr { bus: 0, source: 0, gsi: 2, flags: 0 })\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @3: DevIntSrcOvr(MADT_IntSrcOvr { bus: 0, source: 5, gsi: 5, flags: 13 })\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @4: DevIntSrcOvr(MADT_IntSrcOvr { bus: 0, source: 9, gsi: 9, flags: 13 })\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @5: DevIntSrcOvr(MADT_IntSrcOvr { bus: 0, source: 10, gsi: 10, flags: 13 })\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @6: DevIntSrcOvr(MADT_IntSrcOvr { bus: 0, source: 11, gsi: 11, flags: 13 })\r\n     0d 0[kernel::arch::imp::hw::apic::init] - @7: DevLAPICNMI(MADT_LAPICNMI { processor: 255, flags: 0, lint_num: 1 })\r\n     0n 0[kernel::arch::imp::hw::apic] - Legacy PIC present, disabling\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0xfec00000 => 0xffffb00000285000\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - 170011 17 17\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - regs=[0x0,0x170011,0x0]\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - IOAPIC: { 0xfec00000 - 0 + 24 }\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0xfee00000 => 0xffffb00000286000\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - LAPIC { IDReg=0, Ver=50011, SIR=0x1ff }\r\n     0t 0[kernel::arch::imp::interrupts] - L62: bind_isr(isr=126,callback=0xffffffff801a4860,info=0xffffffff80216af8,idx=0)\r\n     0t 0[kernel::arch::imp::interrupts] - L69: &h = 0xffffffff80218188\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - oldaddr = 0xfee00900\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - IRR[0..8] = [0x0, 0x0, 0x0, 0x0,  0x0, 0x0, 0x0, 0x0]\r\n     0d 0[kernel::modules] - #14: HPET Deps\r\n     0d 0[kernel::modules] - #14: HPET Init\r\n     0t 0[kernel::arch::imp::hw::hpet] - L55: init()\r\n     0t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fffb89, 56)\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000287000\r\n     0d 0[kernel::arch::imp::acpi::internal] - AcpiGetTable: out_ptr = 0xffffb00000287b89\r\n     0d 0[kernel::logging] - AcpiGetTable Dump 0xffffb00000287b89+36\r\n     0d 0[kernel::logging] - AcpiGetTable 0xffffb00000287b89: 48 50 45 54 38 00 00 00  01 03 42 4f 43 48 53 20 |HPET8.....BOCHS |\r\n     0d 0[kernel::logging] - AcpiGetTable 0xffffb00000287b99: 42 58 50 43 48 50 45 54  01 00 00 00 42 58 50 43 |BXPCHPET....BXPC|\r\n     0d 0[kernel::logging] - AcpiGetTable 0xffffb00000287ba9: 01 00 00 00                                      |....            |\r\n     0t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0xfed00000 => 0xffffb00000288000\r\n     0t 0[kernel::arch::imp::interrupts] - L90: bind_free_isr(callback=0xffffffff801a77d0,info=0xffffffff80216b30,idx=2)\r\n     0t 0[kernel::arch::imp::interrupts] - L97: - Using ISR 32\r\n     0t 0[kernel::arch::imp::hw::apic::raw] - L270: set_irq(idx=2,vector=32,apic=0,mode=EdgeHi)\r\n     0d 0[kernel::arch::imp::hw::apic::raw] - Info = 0x10000\r\n     1d 0[kernel::arch::imp::hw::apic::raw] - Disable 0: Info = 0x10000\r\n     1d 0[kernel::modules] - #11: arch Init\r\n     1d 0[kernel::modules] - #7: DeviceManager Init\r\n     1d 0[kernel::modules] - #6: Storage Deps\r\n     2d 0[kernel::modules] - #6: Storage Init\r\n     2d 0[kernel::modules] - #0: VirtIO Init\r\n     2d 0[kernel::device_manager] - Registering driver virtio-fdt-mmio\r\n     2d 0[kernel::modules] - #1: ATA Deps\r\n     2d 0[kernel::modules] - #1: ATA Init\r\n     2d 0[kernel::device_manager] - Registering driver ata-legacy\r\n     2d 0[kernel::device_manager] - Registering driver ata-native\r\n     2d 0[kernel::modules] - #2: PS2 Deps\r\n     2d 0[kernel::modules] - #2: PS2 Init\r\n     3t 0[kernel::arch::imp::acpi::internal::shim_out] - L59: AcpiOsMapMemory(0x7fff1c0, 116)\r\n     3t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0x7fff000 => 0xffffb00000289000\r\n     3d 0[kernel::arch::imp::acpi::internal] - AcpiGetTable: out_ptr = 0xffffb000002891c0\r\n     4d 0[kernel::logging] - AcpiGetTable Dump 0xffffb000002891c0+36\r\n     4d 0[kernel::logging] - AcpiGetTable 0xffffb000002891c0: 46 41 43 50 74 00 00 00  01 c9 42 4f 43 48 53 20 |FACPt.....BOCHS |\r\n     4d 0[kernel::logging] - AcpiGetTable 0xffffb000002891d0: 42 58 50 43 46 41 43 50  01 00 00 00 42 58 50 43 |BXPCFACP....BXPC|\r\n     4d 0[kernel::logging] - AcpiGetTable 0xffffb000002891e0: 01 00 00 00                                      |....            |\r\n     4t 0[input_ps2::i8042] - L36: FADT revision 1, assuming 8042 present\r\n     5d 0[input_ps2::i8042] - can_have_second_port=true, has_second_port=true, port1_works=true, port2_works=true\r\n     5d 0[input_ps2::i8042] - Controller config = 0b00110011\r\n     5d 0[input_ps2::i8042] - Enabling port 1\r\n     5t 0[kernel::arch::imp::interrupts] - L90: bind_free_isr(callback=0xffffffff801a77d0,info=0xffff800000832e80,idx=1)\r\n     6t 0[kernel::arch::imp::interrupts] - L97: - Using ISR 33\r\n     6t 0[kernel::arch::imp::hw::apic::raw] - L270: set_irq(idx=1,vector=33,apic=0,mode=EdgeHi)\r\n     6d 0[kernel::arch::imp::hw::apic::raw] - Info = 0x10000\r\n     6d 0[kernel::arch::imp::hw::apic::raw] - Disable 0: Info = 0x10000\r\n     6d 0[input_ps2::i8042] - Enabling port 2\r\n     7t 0[kernel::arch::imp::interrupts] - L90: bind_free_isr(callback=0xffffffff801a77d0,info=0xffff800000833020,idx=12)\r\n     7t 0[kernel::arch::imp::interrupts] - L97: - Using ISR 34\r\n     7t 0[kernel::arch::imp::hw::apic::raw] - L270: set_irq(idx=12,vector=34,apic=0,mode=EdgeHi)\r\n     7d 0[kernel::arch::imp::hw::apic::raw] - Info = 0x10000\r\n     7d 0[kernel::arch::imp::hw::apic::raw] - Disable 0: Info = 0x10000\r\n     7d 0[kernel::modules] - #3: FS_FAT Deps\r\n     7d 0[kernel::modules] - #8: VFS Deps\r\n     8d 0[kernel::modules] - #8: VFS Init\r\n    10t 0[kernel::vfs::handle] - L85: Any::open(Path(b\"/\"))\r\n    10t 0[kernel::vfs::node] - CacheHandle::from_path(Path(b\"/\"))\r\n    10t 0[kernel::vfs::mount] - L143: Handle::for_path(Path(b\"/\"))\r\n    10t 0[kernel::vfs::path] - L74: Path::starts_with(self=Path(b\"/\"), other=Path(b\"/\"))\r\n    11t 0[kernel::vfs::path] - L83: tail=Path(b\"/\") :: comp=b\"\", t=Path(b\"\")\r\n    11t 0[kernel::vfs::path] - L85: ocomp=b\"\", comp=b\"\"\r\n    11d 0[kernel::vfs::mount] - Return 0'PathBuf(b\"/\")', tail=Path(b\"\")\r\n    11t 0[kernel::vfs::ramfs] - L87: RamFS::get_node_by_inode(0)\r\n    12d 0[kernel::vfs::node] - - tail=Path(b\"\")\r\n    12d 0[kernel::vfs::node] - return CacheHandle { 0:0x0 0xffff800000833580 }\r\n    14t 0[kernel::vfs::ramfs] - L87: RamFS::get_node_by_inode(1)\r\n    14t 0[kernel::vfs::ramfs] - L87: RamFS::get_node_by_inode(2)\r\n    15t 0[kernel::arch::imp::hw::apic::raw] - L200: LAPIC Timer\r\n    15t 0[kernel::vfs::ramfs] - L87: RamFS::get_node_by_inode(3)\r\n    15d 0[kernel::modules] - #3: FS_FAT Init\r\n    15d 0[kernel::modules] - #4: FS_ISO9660 Deps\r\n    15d 0[kernel::modules] - #4: FS_ISO9660 Init\r\n    15d 0[kernel::modules] - #9: PCI Deps\r\n    15d 0[kernel::modules] - #9: PCI Init\r\n    16d 0[kernel::device_manager] - Registering driver bus-pci\r\n    16t 0[kernel::hw::bus_pci] - L179: PCI scan_bus(0)\r\n    16d 0[kernel::hw::bus_pci] - 0x00 Ven:8086 Dev:1237 Class 06000002\r\n    16d 0[kernel::hw::bus_pci] - 0x08 Ven:8086 Dev:7000 Class 06010000\r\n    17d 0[kernel::hw::bus_pci] - 0x09 Ven:8086 Dev:7010 Class 01018000\r\n    17d 0[kernel::hw::bus_pci] - 0x0b Ven:8086 Dev:7113 Class 06800003\r\n    17d 0[kernel::hw::bus_pci] - 0x10 Ven:1013 Dev:00b8 Class 03000000\r\n    17d 0[kernel::hw::bus_pci] - 0x18 Ven:8086 Dev:100e Class 02000003\r\n    17d 0[kernel::device_manager] - Finding driver for pci:0\r\n    18d 0[kernel::device_manager] - Finding driver for pci:8\r\n    18d 0[kernel::device_manager] - Finding driver for pci:9\r\n    19d 0[kernel::hw::bus_pci] - one_value = 0xfff1, size=0x10, value=0xc041\r\n    20d 0[storage_ata] - ControllerRoot::new( 0x1f0, 0x3f6, 14,  0x170, 0x376, 15,  IO(0xc040+0x10)\r\n    21t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0xabe000 => 0xffffb0000028a000\r\n    21t 0[kernel::arch::imp::interrupts] - L90: bind_free_isr(callback=0xffffffff801a77d0,info=0xffff800000834440,idx=14)\r\n    21t 0[kernel::arch::imp::interrupts] - L97: - Using ISR 35\r\n    21t 0[kernel::arch::imp::hw::apic::raw] - L270: set_irq(idx=14,vector=35,apic=0,mode=EdgeHi)\r\n    22d 0[kernel::arch::imp::hw::apic::raw] - Info = 0x10000\r\n    22d 0[kernel::arch::imp::hw::apic::raw] - Disable 0: Info = 0x10000\r\n    22t 0[kernel::memory::virt] - L429: map_hw: Dynamic allocation 0xabf000 => 0xffffb0000028b000\r\n    23t 0[kernel::arch::imp::interrupts] - L90: bind_free_isr(callback=0xffffffff801a77d0,info=0xffff8000008345e0,idx=15)\r\n    23t 0[kernel::arch::imp::interrupts] - L97: - Using ISR 36\r\n    23t 0[kernel::arch::imp::hw::apic::raw] - L270: set_irq(idx=15,vector=36,apic=0,mode=EdgeHi)\r\n    23d 0[kernel::arch::imp::hw::apic::raw] - Info = 0x10000\r\n    23d 0[kernel::arch::imp::hw::apic::raw] - Disable 0: Info = 0x10000\r\n    25l 0[storage_ata] - ATA0: Hard Disk, 100352 sectors, 49MiB\r\n    26l 0[kernel::metadevs::storage] - Logical Volume: ATA-0w 49MiB\r\n    26l 0[storage_ata] - ATA2: ATAPI\r\n    26d 0[kernel::memory::helpers::dma_buffer] - phys = 0x2142d0, source_slice=0xffffa0000000f2d0\r\n    28d 0[kernel::threads] - Task switch to 0xffff800000000800(2 GUI Compositor)\r\n    28t 0[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000001fc28,CR3=0x1ff000,TLS=0xffffa0000001ffd0\r\n    28d 2[gui::windows] - render_thread: Rendering WG 0 'Kernel'\r\n    28t 2[gui::windows] - L181: WindowGroup::redraw: render_order=[(0, [(0,0 + 1152x128), (0,128 + 1280x896)]), (1, [(0,0 + 128x128)])]\r\n    29t 2[gui::windows] - L196: WindowGroup::redraw: 0 'Kernel Log' dirty=[(0,0 + 1280x1024)], vis=[(0,0 + 1152x128), (0,128 + 1280x896)]\r\n    29t 2[gui::windows::winbuf] - L109: WinBuf::blit(winpos=(0,0),rgn=(0,0 + 1152x128))\r\n   104t 2[gui::windows::winbuf] - L109: WinBuf::blit(winpos=(0,0),rgn=(0,128 + 1280x896))\r\n   627t 2[gui::windows] - L196: WindowGroup::redraw: 1 'Logo' dirty=[(0,0 + 128x128)], vis=[(0,0 + 128x128)]\r\n   627t 2[gui::windows::winbuf] - L109: WinBuf::blit(winpos=(1152,0),rgn=(0,0 + 128x128))\r\n   698d 2[gui::windows] - render_thread: Rendering WG 0 'Kernel'\r\n   698t 2[gui::windows] - L181: WindowGroup::redraw: render_order=[(0, [(0,0 + 1152x128), (0,128 + 1280x896)]), (1, [(0,0 + 128x128)])]\r\n   699t 2[kernel::threads::wait_queue] - L32: WaitQueue::wait(...)\r\n   699d 2[kernel::threads] - Task switch to 0xffff8000000002e0(1 IRQ Worker)\r\n   699t 2[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa00000017d68,CR3=0x1ff000,TLS=0xffffa00000017fd0\r\n   699t 1[input_ps2::i8042] - L86: PS2 RX pri 0xfa\r\n   700t 1[input_ps2::i8042] - L86: PS2 RX pri 0xaa\r\n   700d 1[input_ps2] - Byte 0xaa caused State transition None to Enumerating(DSAck)\r\n   700t 1[input_ps2::i8042] - L65: PS2 TX pri 0xf5\r\n   700t 1[input_ps2::i8042] - L86: PS2 RX pri 0xfa\r\n   701d 1[input_ps2] - Byte 0xfa caused State transition Enumerating(DSAck) to Enumerating(IdentAck)\r\n   701t 1[input_ps2::i8042] - L65: PS2 TX pri 0xf2\r\n   701t 1[input_ps2::i8042] - L86: PS2 RX pri 0xfa\r\n   701d 1[input_ps2] - Byte 0xfa caused State transition Enumerating(IdentAck) to Enumerating(IdentB1)\r\n   701t 1[input_ps2::i8042] - L86: PS2 RX pri 0xab\r\n   701d 1[input_ps2] - Byte 0xab caused State transition Enumerating(IdentB1) to Enumerating(IdentB2(171))\r\n   702t 1[input_ps2::i8042] - L86: PS2 RX pri 0x83\r\n   702d 1[input_ps2] - Byte 0x83 caused State transition Enumerating(IdentB2(171)) to Keyboard(Dev { ty: MF2, state: Init(ReqScancodeSetAck), guidev: Instance(1) })\r\n   702t 1[input_ps2::i8042] - L65: PS2 TX pri 0xf0\r\n   702t 1[input_ps2::i8042] - L86: PS2 RX pri 0xfa\r\n   702t 1[input_ps2::i8042] - L65: PS2 TX pri 0x0\r\n   702t 1[input_ps2::i8042] - L86: PS2 RX pri 0x2\r\n   703t 1[input_ps2::i8042] - L86: PS2 RX sec 0xfa\r\n   703t 1[input_ps2::i8042] - L86: PS2 RX sec 0xaa\r\n   703d 1[input_ps2] - Byte 0xaa caused State transition None to Enumerating(DSAck)\r\n   703t 1[input_ps2::i8042] - L65: PS2 TX sec 0xf5\r\n   703t 1[input_ps2::i8042] - L86: PS2 RX sec 0x0\r\n   703t 1[input_ps2::i8042] - L86: PS2 RX sec 0xfa\r\n   703d 1[input_ps2] - Byte 0xfa caused State transition Enumerating(DSAck) to Enumerating(IdentAck)\r\n   703t 1[input_ps2::i8042] - L65: PS2 TX sec 0xf2\r\n   703t 1[input_ps2::i8042] - L86: PS2 RX sec 0xfa\r\n   703d 1[input_ps2] - Byte 0xfa caused State transition Enumerating(IdentAck) to Enumerating(IdentB1)\r\n   704t 1[input_ps2::i8042] - L86: PS2 RX sec 0x0\r\n   704d 1[input_ps2] - Byte 0x0 caused State transition Enumerating(IdentB1) to Mouse(Dev { ty: Std, state: Idle, guidev: Instance(1), btns: 0 })\r\n   704d 1[kernel::threads] - Task switch to 0xffff800000000180(0 ThreadZero)\r\n   704t 1[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000000de68,CR3=0x1ff000,TLS=0xffffa0000000ffd0\r\n   705d 0[storage_scsi] - Type: 0x5\r\n   705d 0[kernel::memory::helpers::dma_buffer] - phys = 0x213c40, source_slice=0xffffa0000000ec40\r\n   706d 0[storage_scsi] - No medium\r\n   706d 0[storage_scsi] - SCSI Volume CdDvd size=None\r\n   706l 0[storage_ata] - ATA1: No disk\r\n   707l 0[storage_ata] - ATA3: No disk\r\n   707d 0[kernel::device_manager] - Finding driver for pci:b\r\n   707d 0[kernel::device_manager] - Finding driver for pci:10\r\n   707d 0[kernel::device_manager] - Finding driver for pci:18\r\n   707d 0[kernel::modules] - #10: MapperMBR Deps\r\n   707d 0[kernel::modules] - #10: MapperMBR Init\r\n   708d 0[kernel::hw::mapper_mbr] - PV 'ATA-0' boot sig 55 aa\r\n   709d 0[kernel::metadevs::storage] - Removing 1 LVs\r\n   710d 0[kernel::hw::mapper_mbr] - Entry { bootable: false, system_id: 131, lba_start: 2048, lba_count: 65536 }\r\n   711l 0[kernel::metadevs::storage] - Logical Volume: ATA-0p0 32MiB\r\n   711d 0[kernel::hw::mapper_mbr] - Entry { bootable: false, system_id: 7, lba_start: 67584, lba_count: 32768 }\r\n   711l 0[kernel::metadevs::storage] - Logical Volume: ATA-0p1 16MiB\r\n   711d 0[kernel::modules] - #12: TSS Deps\r\n   711d 0[kernel::modules] - #12: TSS Init\r\n   711d 0[kernel::threads] - Task switch to 0xffff8000000002e0(1 IRQ Worker)\r\n   711t 0[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa00000017d68,CR3=0x1ff000,TLS=0xffffa00000017fd0\r\n   712d 1[kernel::threads] - Task switch to 0xffff800000000180(0 ThreadZero)\r\n   712t 1[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000000fc78,CR3=0x1ff000,TLS=0xffffa0000000ffd0\r\n   715d 0[kernel] - sysroot = \"Tifflin\"\r\n   715t 0[kernel::vfs::handle] - L85: Any::open(Path(b\"/\"))\r\n   716t 0[kernel::vfs::node] - CacheHandle::from_path(Path(b\"/\"))\r\n   716t 0[kernel::vfs::mount] - L143: Handle::for_path(Path(b\"/\"))\r\n   716t 0[kernel::vfs::path] - L74: Path::starts_with(self=Path(b\"/\"), other=Path(b\"/system\"))\r\n   716t 0[kernel::vfs::path] - L83: tail=Path(b\"/\") :: comp=b\"\", t=Path(b\"\")\r\n   716t 0[kernel::vfs::path] - L85: ocomp=b\"\", comp=b\"\"\r\n   716t 0[kernel::vfs::path] - L74: Path::starts_with(self=Path(b\"/\"), other=Path(b\"/\"))\r\n   716t 0[kernel::vfs::path] - L83: tail=Path(b\"/\") :: comp=b\"\", t=Path(b\"\")\r\n   716t 0[kernel::vfs::path] - L85: ocomp=b\"\", comp=b\"\"\r\n   717d 0[kernel::vfs::mount] - Return 0'PathBuf(b\"/\")', tail=Path(b\"\")\r\n   717d 0[kernel::vfs::node] - - tail=Path(b\"\")\r\n   717d 0[kernel::vfs::node] - return CacheHandle { 0:0x0 0xffff800000833580 }\r\n   717t 0[kernel::vfs::ramfs] - L87: RamFS::get_node_by_inode(4)\r\n   717l 0[kernel] - Loading userland '/system/Tifflin/bin/loader' args '/system/Tifflin/bin/init'\r\n   717t 0[kernel::vfs::node] - CacheHandle::from_path(Path(b\"/system/Tifflin/bin/loader\"))\r\n   718t 0[kernel::vfs::mount] - L143: Handle::for_path(Path(b\"/system/Tifflin/bin/loader\"))\r\n   718t 0[kernel::vfs::path] - L74: Path::starts_with(self=Path(b\"/system/Tifflin/bin/loader\"), other=Path(b\"/system\"))\r\n   718t 0[kernel::vfs::path] - L83: tail=Path(b\"/system/Tifflin/bin/loader\") :: comp=b\"\", t=Path(b\"system/Tifflin/bin/loader\")\r\n   718t 0[kernel::vfs::path] - L85: ocomp=b\"\", comp=b\"\"\r\n   718t 0[kernel::vfs::path] - L83: tail=Path(b\"system/Tifflin/bin/loader\") :: comp=b\"system\", t=Path(b\"Tifflin/bin/loader\")\r\n   718t 0[kernel::vfs::path] - L85: ocomp=b\"system\", comp=b\"system\"\r\n   718t 0[kernel::vfs::path] - L83: tail=Path(b\"Tifflin/bin/loader\") :: comp=b\"Tifflin\", t=Path(b\"bin/loader\")\r\n   718d 0[kernel::vfs::mount] - Return 1'PathBuf(b\"/system\")', tail=Path(b\"Tifflin/bin/loader\")\r\n   719d 0[kernel::vfs::node] - - tail=Path(b\"Tifflin/bin/loader\")\r\n   719d 0[kernel::vfs::node] - - seg=b\"Tifflin\" : DIR\r\n   719t 0[blockcache] - L71: Miss: 16711680\r\n   720d 0[fs_fat::dir] - lookup(): ent = End\r\n   720e 0[kernel] - Unable to open initial userland loader '/system/Tifflin/bin/loader': NotFound\r\n   720i 0[kernel] - Entering idle\r\n   721t 0[kernel] - L164: TID0 napping\r\n   721d 0[kernel::threads] - Task switch to 0xffff8000000002e0(1 IRQ Worker)\r\n   721t 0[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa00000017d68,CR3=0x1ff000,TLS=0xffffa00000017fd0\r\n   721d 1[kernel::threads] - Task switch to 0xffff800000000180(0 ThreadZero)\r\n   721t 1[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000000fc78,CR3=0x1ff000,TLS=0xffffa0000000ffd0\r\n   721t 0[kernel] - L164: TID0 napping\r\n   721d 0[kernel::threads] - Task switch to self, idle\r\n   721t 0[kernel::arch::imp::threads] - L210: Switching to RSP=0xffffa0000000fc78,CR3=0x1ff000,TLS=0xffffa0000000ffd0\r\n···"}}