Back to build
Raw log | Switch to full mode | Login

{ "43d3c4033b319b10": { "output": "2019/03/26 17:01:41 QEMU invocation:\n[/b/s/w/ir/qemu/bin/qemu-system-aarch64 -machine virt,gic_version=host -cpu host -enable-kvm -m 4096 -smp 4 -nographic -serial stdio -monitor none -kernel /b/s/w/ir/qemu-boot-shim-created_by_recipe.bin -initrd /b/s/w/ir/fuchsia-QEMU.zbi -drive id=maindisk,file=/b/s/w/ir/obj/build/images/fvm.blk,format=raw,if=none -device virtio-blk-pci,drive=maindisk -drive id=testdisk,file=/b/s/w/ir/output.fs,format=raw,if=none -device virtio-blk-pci,drive=testdisk,addr=06.0 -append zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds kernel.halt-on-panic=true devmgr.suspend-timeout-debug=true TERM=dumb]\n[00000.000] 00000.00000> PMM: boot reserve add [0x48d40000, 0x48f04fff]\r\n[00000.000] 00000.00000> mem_arena.base 0x40000000 size 0x8000000\r\n[00000.000] 00000.00000> overriding mem arena 0 base from FDT: 0x40000000\r\n[00000.000] 00000.00000> overriding mem arena 0 size from FDT: 0x100000000\r\n[00000.000] 00000.00000> detected GICv3\r\n[00000.000] 00000.00000> PSCI version 1.0\r\n[00000.000] 00000.00000> PSCI supported features:\r\n[00000.000] 00000.00000> \tPSCI64_SYSTEM_OFF 0\r\n[00000.000] 00000.00000> \tPSCI64_SYSTEM_RESET 0\r\n[00000.000] 00000.00000> \tPSCI64_CPU_ON 0\r\n[00000.000] 00000.00000> \tPSCI64_CPU_OFF 0\r\n[00000.000] 00000.00000> arm generic timer freq 200000000 Hz\r\n[00002.388] 00000.00000> cntpct_per_ns: 00000000.3333333333333333\r\n[00002.388] 00000.00000> ns_per_cntpct: 00000005.0000000000000000\r\n[00002.388] 00000.00000> test_time_conversion_check_result:253: FAIL, off by 230584300921369395\r\n[00002.388] 00000.00000> reserving ramdisk phys range [0x48000000, 0x48be3fff]\r\n[00002.388] 00000.00000> PMM: boot reserve add [0x48000000, 0x48be3fff]\r\n[00002.441] 00000.00000> PMM: boot reserve marking WIRED [0x48000000, 0x48be3fff]\r\n[00002.441] 00000.00000> PMM: boot reserve marking WIRED [0x48d40000, 0x48f04fff]\r\n[00002.442] 00000.00000> \r\n[00002.442] 00000.00000> welcome to Zircon\r\n[00002.442] 00000.00000> \r\n[00002.442] 00000.00000> KASLR: .text section at 0xffffffff10000000\r\n[00002.442] 00000.00000> INIT: cpu 0, calling hook 0xffffffff10011b20 (global_prng_seed) at level 0x30000, flags 0x1\r\n[00002.456] 00000.00000> initializing vm pre-heap\r\n[00002.456] 00000.00000> VM: marking boot alloc used range [0x48f05000, 0x48f0a040)\r\n[00002.458] 00000.00000> INIT: cpu 0, calling hook 0xffffffff1001f060 (elf_build_id) at level 0x4fffe, flags 0x1\r\n[00002.458] 00000.00000> INIT: cpu 0, calling hook 0xffffffff1001f230 (version) at level 0x4ffff, flags 0x1\r\n[00002.458] 00000.00000> version:\r\n[00002.458] 00000.00000> \tarch: arm64\r\n[00002.458] 00000.00000> \tplatform: XXX\r\n[00002.458] 00000.00000> \ttarget: XXX\r\n[00002.458] 00000.00000> \tproject: arm64\r\n[00002.458] 00000.00000> \tbuildid: git-2ea98fb15d39b1fcf1e2c228918cea7b05c4938c-dirty\r\n[00002.458] 00000.00000> \tELF build ID: 7017ffbe4a2ec65d9d8ffa3d4b2635d77942414c\r\n[00002.458] 00000.00000> initializing heap\r\n[00002.458] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100014c0 (arm_resource_init) at level 0x50000, flags 0x1\r\n[00002.458] 00000.00000> INIT: cpu 0, calling hook 0xffffffff10003600 (console) at level 0x50000, flags 0x1\r\n[00002.458] 00000.00000> initializing vm\r\n[00002.458] 00000.00000> VM: reserving kernel region [0xffffffff10000000, 0xffffffff10100000) flags 0x28 name 'kernel_code'\r\n[00002.458] 00000.00000> VM: reserving kernel region [0xffffffff10100000, 0xffffffff10145000) flags 0x8 name 'kernel_rodata'\r\n[00002.458] 00000.00000> VM: reserving kernel region [0xffffffff10145000, 0xffffffff1014f000) flags 0x18 name 'kernel_data'\r\n[00002.458] 00000.00000> VM: reserving kernel region [0xffffffff10150000, 0xffffffff101c5000) flags 0x18 name 'kernel_bss'\r\n[00002.458] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100014b0 (platform_postvm) at level 0x60000, flags 0x1\r\n[00002.458] 00000.00000> CreateSubVmarInternal:114: warning: mapping periph has conflicting cache policies: vmo 00 arch_mmu_flags 02.\r\n[00002.458] 00000.00000> initializing kernel\r\n[00002.458] 00000.00000> initializing mp\r\n[00002.458] 00000.00000> initializing timers\r\n[00002.458] 00000.00000> INIT: cpu 0, calling hook 0xffffffff10001920 (platform_init_pre_thread) at level 0x7ffff, flags 0x1\r\n[00002.461] 00000.00000> System topology: CPU 0:0:0:0\r\n[00002.461] 00000.00000> System topology: CPU 0:0:0:1\r\n[00002.461] 00000.00000> System topology: CPU 0:0:0:2\r\n[00002.461] 00000.00000> System topology: CPU 0:0:0:3\r\n[00002.461] 00000.00000> INIT: cpu 0, calling hook 0xffffffff10011d70 (global_prng_thread_safe) at level 0x7ffff, flags 0x1\r\n[00002.461] 00000.00000> INIT: cpu 0, calling hook 0xffffffff1001e130 (debuglog) at level 0x7ffff, flags 0x1\r\n[00002.461] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100cfc40 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1\r\n[00002.461] 00000.00000> thread set priority experiment is : ENABLED\r\n[00002.461] 00000.00000> creating bootstrap completion thread\r\n[00002.512] 00000.00000> top of bootstrap2()\r\n[00002.512] 00000.00000> INIT: cpu 0, calling hook 0xffffffff1002de70 (libobject) at level 0x80000, flags 0x1\r\n[00002.512] 00000.00000> OOM: started thread\r\n[00002.515] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100eba80 (dpc) at level 0x80000, flags 0x1\r\n[00002.516] 00000.00000> ARM boot EL1\r\n[00002.516] 00000.00000> ARM cpu 0: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000000 aff 0:0:0:0\r\n[00002.516] 00000.00000> ARM Features: fp asimd aes pmull sha1 sha2 crc32 atomics rdm \r\n[00002.516] 00000.00000> ARM cache line sizes: icache 64 dcache 64 zva 64\r\n[00002.516] 00000.00000> ==== ARM64 CACHE INFO CORE 0 ====\r\n[00002.516] 00000.00000> Inner Boundary = L0\r\n[00002.516] 00000.00000> Level of Unification Uniprocessor = L1\r\n[00002.516] 00000.00000> Level of Coherence = L3\r\n[00002.516] 00000.00000> Level of Unification Inner Shareable = L1\r\n[00002.516] 00000.00000> L1 Details:\tData Cache, sets=64, associativity=8, line size=64 bytes\r\n[00002.516] 00000.00000> \t\tInstruction Cache, sets=64, associativity=8, line size=64 bytes\r\n[00002.516] 00000.00000> L2 Details:\tUnified Cache, sets=512, associativity=8, line size=64 bytes\r\n[00002.516] 00000.00000> L3 Details:\tUnified Cache, sets=16384, associativity=32, line size=64 bytes\r\n[00002.516] 00000.00000> L4 Details:\tNot Implemented\r\n[00002.516] 00000.00000> L5 Details:\tNot Implemented\r\n[00002.516] 00000.00000> L6 Details:\tNot Implemented\r\n[00002.516] 00000.00000> L7 Details:\tNot Implemented\r\n[00002.516] 00000.00000> initializing platform\r\n[00002.516] 00000.00000> INIT: cpu 0, calling hook 0xffffffff1008d270 (arm64_perfmon) at level 0x90000, flags 0x1\r\n[00002.516] 00000.00000> Trying to start cpu 1 returned: 0\r\n[00002.516] 00000.00000> Trying to start cpu 2 returned: 0\r\n[00002.516] 00000.00000> Trying to start cpu 3 returned: 0\r\n[00002.516] 00000.00000> initializing target\r\n[00002.516] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100827e0 (platform_dev_init) at level 0xa0000, flags 0x1\r\n[00002.516] 00000.00000> UART: started IRQ driven TX\r\n[00002.516] 00000.00000> moving to last init level\r\n[00002.516] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100040d0 (kernel_shell) at level 0xc0000, flags 0x1\r\n[00002.516] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100e83c0 (ktrace) at level 0xc0000, flags 0x1\r\n[00002.583] 00000.00000> ktrace: buffer at 0xffff008c2d954000 (33554432 bytes)\r\n[00002.583] 00000.00000> INIT: cpu 0, calling hook 0xffffffff100fa450 (userboot) at level 0xc0000, flags 0x1\r\n[00002.583] 00000.00000> userboot: ramdisk 0xbe4000 @ 0xffff000048000000\r\n[00002.583] 00000.00000> userboot: userboot rodata 0 @ [0x66d9e178d000,0x66d9e1790000)\r\n[00002.583] 00000.00000> userboot: userboot code 0x3000 @ [0x66d9e1790000,0x66d9e179a000)\r\n[00002.583] 00000.00000> userboot: vdso/full rodata 0 @ [0x66d9e179a000,0x66d9e17a1000)\r\n[00002.583] 00000.00000> userboot: vdso/full code 0x7000 @ [0x66d9e17a1000,0x66d9e17a3000)\r\n[00002.583] 00000.00000> userboot: entry point @ 0x66d9e1790c30\r\n[00002.592] 01032.01039> userboot: option \"zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds\"\r\n[00002.592] 01032.01039> userboot: option \"kernel.halt-on-panic=true\"\r\n[00002.592] 01032.01039> userboot: option \"devmgr.suspend-timeout-debug=true\"\r\n[00002.592] 01032.01039> userboot: option \"TERM=dumb\"\r\n[00002.679] 01032.01039> userboot: searching bootfs for 'bin/bootsvc'\r\n[00002.679] 01032.01039> userboot: 'bin/bootsvc' has PT_INTERP \"lib/ld.so.1\"\r\n[00002.679] 01032.01039> userboot: searching bootfs for 'lib/ld.so.1'\r\n[00002.679] 01032.01039> userboot: userboot: loaded lib/ld.so.1 at 0xcdf483e37000, entry point 0xcdf483e71744\r\n[00002.679] 01032.01039> userboot: userboot: loaded vDSO at 0xe7be9b93e000, entry point 0x0\r\n[00002.684] 01032.01039> userboot: process bin/bootsvc started.\r\n[00002.684] 01032.01039> userboot: waiting for loader-service requests...\r\n[00002.684] 01032.01039> userboot: searching bootfs for 'lib/libasync-default.so'\r\n[00002.687] 01032.01039> userboot: searching bootfs for 'lib/libfdio.so'\r\n[00002.688] 01032.01039> userboot: searching bootfs for 'lib/libtrace-engine.so'\r\n[00002.689] 01032.01039> userboot: searching bootfs for 'lib/liblaunchpad.so'\r\n[00002.691] 01046.01049> {{{reset}}}\r\n[00002.691] 01046.01049> {{{module:0:<application>:elf:ae6220da727031f4c51d5937091b759b3d5ce41f}}}\r\n[00002.691] 01046.01049> {{{mmap:0x19c9f2ba000:0x39000:load:0:rx:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0x19c9f2f3000:0x4000:load:0:rw:0x39000}}}\r\n[00002.691] 01046.01049> dso: id=ae6220da727031f4c51d5937091b759b3d5ce41f base=0x0000019c9f2ba000 name=<application>\r\n[00002.691] 01046.01049> {{{module:0x2:<vDSO>:elf:fa79abf756e2f760634480eb9fb0af6fcbf1b3dd}}}\r\n[00002.691] 01046.01049> {{{mmap:0xe7be9b93e000:0x7000:load:0x2:r:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0xe7be9b945000:0x2000:load:0x2:rx:0x7000}}}\r\n[00002.691] 01046.01049> dso: id=fa79abf756e2f760634480eb9fb0af6fcbf1b3dd base=0x0000e7be9b93e000 name=<vDSO>\r\n[00002.691] 01046.01049> {{{module:0x3:libasync-default.so:elf:019c11aa6b6888bd30643eb7127e36d41a153241}}}\r\n[00002.691] 01046.01049> {{{mmap:0x1fd3a3bed000:0x1000:load:0x3:rx:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0x1fd3a3bee000:0x1000:load:0x3:rw:0x1000}}}\r\n[00002.691] 01046.01049> dso: id=019c11aa6b6888bd30643eb7127e36d41a153241 base=0x00001fd3a3bed000 name=libasync-default.so\r\n[00002.691] 01046.01049> {{{module:0x4:libfdio.so:elf:8c556a32bfe4d91a2fd011ad52db9cd4f628c5ec}}}\r\n[00002.691] 01046.01049> {{{mmap:0x1dc397b4f000:0x31000:load:0x4:rx:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0x1dc397b80000:0x4000:load:0x4:rw:0x31000}}}\r\n[00002.691] 01046.01049> dso: id=8c556a32bfe4d91a2fd011ad52db9cd4f628c5ec base=0x00001dc397b4f000 name=libfdio.so\r\n[00002.691] 01046.01049> {{{module:0x5:libtrace-engine.so:elf:a698b85192cbcc7846ee707697854fd070f2707c}}}\r\n[00002.691] 01046.01049> {{{mmap:0x3dacc647c000:0x9000:load:0x5:rx:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0x3dacc6486000:0x2000:load:0x5:rw:0xa000}}}\r\n[00002.691] 01046.01049> dso: id=a698b85192cbcc7846ee707697854fd070f2707c base=0x00003dacc647c000 name=libtrace-engine.so\r\n[00002.691] 01046.01049> {{{module:0x6:liblaunchpad.so:elf:828a82ea6a4da4bd35986769f4e96db0b969e641}}}\r\n[00002.691] 01046.01049> {{{mmap:0x1fcc925f1000:0x7000:load:0x6:rx:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0x1fcc925f8000:0x2000:load:0x6:rw:0x7000}}}\r\n[00002.691] 01046.01049> dso: id=828a82ea6a4da4bd35986769f4e96db0b969e641 base=0x00001fcc925f1000 name=liblaunchpad.so\r\n[00002.691] 01046.01049> {{{module:0x1:libc.so:elf:f0d095644de5021ff1b51dfeaaf5f6b012cd84bd}}}\r\n[00002.691] 01046.01049> {{{mmap:0xcdf483e37000:0xcd000:load:0x1:rx:0}}}\r\n[00002.691] 01046.01049> {{{mmap:0xcdf483f05000:0x6000:load:0x1:rw:0xce000}}}\r\n[00002.691] 01046.01049> dso: id=f0d095644de5021ff1b51dfeaaf5f6b012cd84bd base=0x0000cdf483e37000 name=libc.so\r\n[00002.691] 01046.01049> bootsvc: Starting...\r\n[00002.691] 01046.01049> bootsvc: Creating bootfs service...\r\n[00002.692] 01046.01049> bootsvc: Retrieving boot image...\r\n[00002.692] 01046.01049> bootsvc: Loading boot arguments...\r\n[00002.692] 01046.01049> bootsvc:: Creating svcfs service...\r\n[00002.692] 01046.01049> bootsvc: Loading kernel VMOs...\r\n[00002.692] 01046.01049> bootsvc: Creating loader service...\r\n[00002.692] 01046.01049> bootsvc: Launching next process...\r\n[00002.731] 01103.01116> devcoordinator: init\r\n[00002.734] 01103.01116> devcoordinator: launch /boot/bin/svchost (svchost) OK\r\n[00002.738] 01265.01274> fshost: started.\r\n[00002.739] 01103.01116> devcoordinator: launch /boot/bin/fshost (fshost) OK\r\n[00002.742] 01103.01349> devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK\r\n[00002.745] 01103.01417> devcoordinator: launch /boot/bin/netsvc (netsvc) OK\r\n[00002.748] 01103.01417> devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK\r\n[00002.756] 01103.01116> devcoordinator: full system required, ignoring fallback drivers until /system is loaded\r\n[00002.759] 02117.02131> devhost: trace provider registry begun\r\n[00002.760] 01103.01116> devcoordinator: launch devhost 'devhost:sys': pid=2117\r\n[00002.763] 02204.02218> devhost: trace provider registry begun\r\n[00002.764] 01103.01116> devcoordinator: launch devhost 'devhost:test': pid=2204\r\n[00002.767] 02282.02296> devhost: trace provider registry begun\r\n[00002.768] 01103.01116> devcoordinator: launch devhost 'devhost:root': pid=2282\r\n[00002.771] 02369.02383> devhost: trace provider registry begun\r\n[00002.772] 01103.01116> devcoordinator: launch devhost 'devhost:misc': pid=2369\r\n[00002.774] 02117.02131> platform bus: VID: 1 PID: 1 board: \"qemu\"\r\n[00002.778] 02117.02131> GetHwProperties: No PM support\r\n[00002.778] 02117.02131> devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2\r\n[00002.781] 01103.01116> devcoordinator: rpc: bind-driver 'cpu-trace' status -2\r\n[00002.783] 01103.01410> devcoordinator: launch /boot/bin/sh (sh:console) OK\r\n[00002.785] 02117.02131> [sysmem_init 28] async_get_default_dispatcher(): 0x651bb4f70a0\r\n[00002.785] 02117.02131> [sysmem_bind 40] sysmem_bind()\r\n[00002.790] 02687.02701> devhost: trace provider registry begun\r\n[00002.791] 01103.01116> devcoordinator: launch devhost 'devhost:pdev:pci': pid=2687\r\n[00002.794] 02775.02789> devhost: trace provider registry begun\r\n[00002.795] 01103.01116> devcoordinator: launch devhost 'devhost:pdev:00:00:6': pid=2775\r\n[00002.799] 02906.02920> devhost: trace provider registry begun\r\n[00002.800] 01103.01116> devcoordinator: launch devhost 'devhost:pci#1:1af4:1000': pid=2906\r\n[00002.803] 02999.03013> devhost: trace provider registry begun\r\n[00002.804] 01103.01116> devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2999\r\n$ [00002.820] 03225.03239> devhost: trace provider registry begun\r\n[00002.821] 01103.01116> devcoordinator: launch devhost 'devhost:pci#3:1af4:1001': pid=3225\r\n[00002.826] 01451.01465> netifc: ? /dev/class/ethernet/000\r\n[00002.827] 01451.01465> netifc: create 512 eth buffers\r\n[00002.829] 01451.01465> macaddr: 52:54:00:12:34:56\r\n[00002.829] 01451.01465> ip6addr: fe80::5054:4d:fe12:3456\r\n[00002.829] 01451.01465> snmaddr: ff02::1:ff12:3456\r\n[00002.829] 01451.01465> netsvc: using /dev/class/ethernet/000\r\n[00002.832] 01451.01465> netsvc: nodename='swarm-donut-petri-acre'\r\n[00002.832] 01451.01465> netsvc: start\r\n[00002.834] 01265.01274> fshost: /dev/class/block/000: FVM?\r\n[00002.836] 01046.01094> bootsvc: launched bin/devcoordinator\r\n[00002.843] 01265.01274> devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK\r\n[00002.859] 01032.01039> userboot: loader-service channel peer closed\r\n[00002.859] 01032.01039> userboot: finished!\r\n[00003.028] 01265.01274> fshost: starting 'bin/pkgsvr' '0f440f657996297a568b9862ef0297fc42cd42ab07cfc595fe905737f6c7bfc3'...\r\n[00003.041] 01265.01274> devcoordinator: launch bin/pkgsvr (pkgfs) OK\r\n[00003.060] 03644.03663> pkgsvr: system: will be served from 0f440f657996297a568b9862ef0297fc42cd42ab07cfc595fe905737f6c7bfc3\r\n[00003.062] 03644.03663> pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob\r\n[00003.065] 01265.01274> fshost: mounting minfs\r\n[00003.068] 01265.01274> devcoordinator: launch /boot/bin/minfs (minfs:/data) OK\r\n[00003.159] 01103.03897> devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so'\r\n[00003.181] 01103.01575> devcoordinator: launch /system/bin/appmgr (appmgr) OK\r\n[00003.181] 01103.01575> autorun: starting '/boot/bin/sh' '/boot/infra/runcmds'...\r\n[00003.194] 01103.03897> devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so'\r\n[00003.207] 01103.01575> devcoordinator: launch /boot/bin/sh (autorun:system) OK\r\n[00003.238] 01103.03897> devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so'\r\n[00003.266] 01103.03897> devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so'\r\n[00003.283] 01103.03897> devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so'\r\n[00003.327] 01103.03897> devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so'\r\n[00003.377] 01103.03897> devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so'\r\n[00003.413] 04076.04097> {{{reset}}}\r\n[00003.413] 04076.04097> {{{module:0:<application>:elf:1e37aba34342ad40}}}\r\n[00003.413] 04076.04097> {{{mmap:0xf51b4bfe9000:0xef000:load:0:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xf51b4c0d9000:0x4c2000:load:0:rx:0xf0000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xf51b4c5a9000:0x72000:load:0:rw:0x5c0000}}}\r\n[00003.413] 04076.04097> dso: id=1e37aba34342ad40 base=0x0000f51b4bfe9000 name=/system/bin/appmgr\r\n[00003.413] 04076.04097> {{{module:0x3:libclang_rt.asan.so:elf:3691f56d7e7762f6}}}\r\n[00003.413] 04076.04097> {{{mmap:0x444e53aea000:0x20000:load:0x3:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0x444e53b0a000:0x38000:load:0x3:rx:0x20000}}}\r\n[00003.413] 04076.04097> {{{mmap:0x444e53b4a000:0x982000:load:0x3:rw:0x60000}}}\r\n[00003.413] 04076.04097> dso: id=3691f56d7e7762f6 base=0x0000444e53aea000 name=libclang_rt.asan.so\r\n[00003.413] 04076.04097> {{{module:0x6:libfxl_logging.so:elf:e8e3d1d0f4727dfb}}}\r\n[00003.413] 04076.04097> {{{mmap:0xc2d7e2131000:0x4000:load:0x6:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xc2d7e2141000:0xa000:load:0x6:rx:0x10000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xc2d7e2151000:0x21000:load:0x6:rw:0x20000}}}\r\n[00003.413] 04076.04097> dso: id=e8e3d1d0f4727dfb base=0x0000c2d7e2131000 name=libfxl_logging.so\r\n[00003.413] 04076.04097> {{{module:0x7:libfdio.so:elf:91a51990b6de2d57}}}\r\n[00003.413] 04076.04097> {{{mmap:0x700834f92000:0x14000:load:0x7:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0x700834fa6000:0x46000:load:0x7:rx:0x14000}}}\r\n[00003.413] 04076.04097> {{{mmap:0x700834fec000:0xe000:load:0x7:rw:0x5a000}}}\r\n[00003.413] 04076.04097> dso: id=91a51990b6de2d57 base=0x0000700834f92000 name=libfdio.so\r\n[00003.413] 04076.04097> {{{module:0x8:libasync-default.so:elf:42b9904800a7a80e}}}\r\n[00003.413] 04076.04097> {{{mmap:0x993441098000:0x1000:load:0x8:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0x993441099000:0x1000:load:0x8:rx:0x1000}}}\r\n[00003.413] 04076.04097> {{{mmap:0x99344109a000:0x1000:load:0x8:rw:0x2000}}}\r\n[00003.413] 04076.04097> dso: id=42b9904800a7a80e base=0x0000993441098000 name=libasync-default.so\r\n[00003.413] 04076.04097> {{{module:0x9:libsyslog.so:elf:d5f87f783523f4fa}}}\r\n[00003.413] 04076.04097> {{{mmap:0xb9ffe1388000:0x3000:load:0x9:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xb9ffe138b000:0x6000:load:0x9:rx:0x3000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xb9ffe1391000:0x3000:load:0x9:rw:0x9000}}}\r\n[00003.413] 04076.04097> dso: id=d5f87f783523f4fa base=0x0000b9ffe1388000 name=libsyslog.so\r\n[00003.413] 04076.04097> {{{module:0xa:libtrace-engine.so:elf:5a9539af56589baa}}}\r\n[00003.413] 04076.04097> {{{mmap:0xd4f8ff8ad000:0x5000:load:0xa:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xd4f8ff8b2000:0xa000:load:0xa:rx:0x5000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xd4f8ff8bc000:0x4000:load:0xa:rw:0xf000}}}\r\n[00003.413] 04076.04097> dso: id=5a9539af56589baa base=0x0000d4f8ff8ad000 name=libtrace-engine.so\r\n[00003.413] 04076.04097> {{{module:0xb:libbacktrace.so:elf:33ceb9bd80c10641}}}\r\n[00003.413] 04076.04097> {{{mmap:0x79a376021000:0x4000:load:0xb:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0x79a376025000:0x1d000:load:0xb:rx:0x4000}}}\r\n[00003.413] 04076.04097> {{{mmap:0x79a376042000:0x3000:load:0xb:rw:0x21000}}}\r\n[00003.413] 04076.04097> dso: id=33ceb9bd80c10641 base=0x000079a376021000 name=libbacktrace.so\r\n[00003.413] 04076.04097> {{{module:0xc:libngunwind.so:elf:feadc20c728cf83b}}}\r\n[00003.413] 04076.04097> {{{mmap:0xa30b4532f000:0xc000:load:0xc:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xa30b4533b000:0x2a000:load:0xc:rx:0xc000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xa30b45365000:0x4e000:load:0xc:rw:0x36000}}}\r\n[00003.413] 04076.04097> dso: id=feadc20c728cf83b base=0x0000a30b4532f000 name=libngunwind.so\r\n[00003.413] 04076.04097> {{{module:0x2:<vDSO>:elf:fa79abf756e2f760634480eb9fb0af6fcbf1b3dd}}}\r\n[00003.413] 04076.04097> {{{mmap:0xa401900fc000:0x7000:load:0x2:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xa40190103000:0x2000:load:0x2:rx:0x7000}}}\r\n[00003.413] 04076.04097> dso: id=fa79abf756e2f760634480eb9fb0af6fcbf1b3dd base=0x0000a401900fc000 name=<vDSO>\r\n[00003.413] 04076.04097> {{{module:0xd:libc++.so.2:elf:7a6a711bab89a3c8}}}\r\n[00003.413] 04076.04097> {{{mmap:0x7bc3b441e000:0xa8000:load:0xd:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0x7bc3b44ce000:0x177000:load:0xd:rx:0xb0000}}}\r\n[00003.413] 04076.04097> {{{mmap:0x7bc3b464e000:0x45000:load:0xd:rw:0x230000}}}\r\n[00003.413] 04076.04097> dso: id=7a6a711bab89a3c8 base=0x00007bc3b441e000 name=libc++.so.2\r\n[00003.413] 04076.04097> {{{module:0x4:libc++abi.so.1:elf:afabd14fa351bf88}}}\r\n[00003.413] 04076.04097> {{{mmap:0x5a53305de000:0x33000:load:0x4:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0x5a533061e000:0x60000:load:0x4:rx:0x40000}}}\r\n[00003.413] 04076.04097> {{{mmap:0x5a533067e000:0x21000:load:0x4:rw:0xa0000}}}\r\n[00003.413] 04076.04097> dso: id=afabd14fa351bf88 base=0x00005a53305de000 name=libc++abi.so.1\r\n[00003.413] 04076.04097> {{{module:0x5:libunwind.so.1:elf:5214f8492511c394}}}\r\n[00003.413] 04076.04097> {{{mmap:0xd0111c438000:0x11000:load:0x5:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xd0111c458000:0xf000:load:0x5:rx:0x20000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xd0111c468000:0x21000:load:0x5:rw:0x30000}}}\r\n[00003.413] 04076.04097> dso: id=5214f8492511c394 base=0x0000d0111c438000 name=libunwind.so.1\r\n[00003.413] 04076.04097> {{{module:0x1:libc.so:elf:590be9ccb5d9cb4d}}}\r\n[00003.413] 04076.04097> {{{mmap:0xfa3c93f56000:0x77000:load:0x1:r:0}}}\r\n[00003.413] 04076.04097> {{{mmap:0xfa3c93fcd000:0xf0000:load:0x1:rx:0x77000}}}\r\n[00003.413] 04076.04097> {{{mmap:0xfa3c940bd000:0x20000:load:0x1:rw:0x167000}}}\r\n[00003.413] 04076.04097> dso: id=590be9ccb5d9cb4d base=0x0000fa3c93f56000 name=libc.so\r\n[00003.413] 04076.04097> =================================================================\r\n[00003.413] 04076.04097> \u001b[1m\u001b[31mERROR: AddressSanitizer: alloc-dealloc-mismatch (operator new vs free) on 0xc7ed2fd0f7c0\r\n[00003.415] 04076.04097> \u001b[1m\u001b[0m{{{bt:0:0x444e53b2e0ec}}}\r\n[00003.415] 04076.04097> {{{bt:1:0x444e53b25aac}}}\r\n[00003.415] 04076.04097> {{{bt:2:0x444e53b30f74}}}\r\n[00003.415] 04076.04097> {{{bt:3:0x444e53b31550}}}\r\n[00003.415] 04076.04097> {{{bt:4:0x444e53b1df2c}}}\r\n[00003.415] 04076.04097> {{{bt:5:0x444e53b2e154}}}\r\n[00003.415] 04076.04097> {{{bt:6:0xf51b4c1154c8}}}\r\n[00003.415] 04076.04097> {{{bt:7:0xf51b4c1b1134}}}\r\n[00003.415] 04076.04097> {{{bt:8:0xf51b4c154a90}}}\r\n[00003.415] 04076.04097> {{{bt:9:0xf51b4c13f398}}}\r\n[00003.415] 04076.04097> {{{bt:10:0xf51b4c0de2b4}}}\r\n[00003.415] 04076.04097> {{{bt:11:0xf51b4c0d97a0}}}\r\n[00003.415] 04076.04097> {{{bt:12:0xf51b4c0eb2d4}}}\r\n[00003.415] 04076.04097> {{{bt:13:0xfa3c94033ad4}}}\r\n[00003.415] 04076.04097> {{{bt:14:0xfa3c940341b4}}}\r\n[00003.415] 04076.04097> {{{bt:15:0xf51b4c0d9018}}}\r\n[00003.415] 04076.04097> \r\n[00003.416] 04076.04097> \u001b[1m\u001b[32m0xc7ed2fd0f7c0 is located 0 bytes inside of 32-byte region [0xc7ed2fd0f7c0,0xc7ed2fd0f7e0)\r\n[00003.416] 04076.04097> \u001b[1m\u001b[0m\u001b[1m\u001b[35mallocated by thread T0 (initial-thread) here:\u001b[1m\u001b[0m\r\n[00003.416] 04076.04097> {{{bt:0:0x444e53b3813c}}}\r\n[00003.416] 04076.04097> {{{bt:1:0x7bc3b45b8bd4}}}\r\n[00003.416] 04076.04097> {{{bt:2:0xf51b4c0de180}}}\r\n[00003.416] 04076.04097> {{{bt:3:0xf51b4c115404}}}\r\n[00003.416] 04076.04097> {{{bt:4:0xf51b4c1b1134}}}\r\n[00003.416] 04076.04097> {{{bt:5:0xf51b4c154a90}}}\r\n[00003.416] 04076.04097> {{{bt:6:0xf51b4c13f398}}}\r\n[00003.416] 04076.04097> {{{bt:7:0xf51b4c0de2b4}}}\r\n[00003.416] 04076.04097> {{{bt:8:0xf51b4c0d97a0}}}\r\n[00003.416] 04076.04097> {{{bt:9:0xf51b4c0eb2d4}}}\r\n[00003.416] 04076.04097> {{{bt:10:0xfa3c94033ad4}}}\r\n[00003.416] 04076.04097> \r\n[00003.416] 04076.04097> SUMMARY: AddressSanitizer: alloc-dealloc-mismatch {{{bt:0:0x444e53b2e0ec}}}\r\n[00003.416] 04076.04097> HINT: if you don't care about these errors you may set ASAN_OPTIONS=alloc_dealloc_mismatch=0\r\n[00003.416] 04076.04097> ABORTING\r\n[00003.511] 04155.04169> /boot/infra/runcmds: 1: /boot/infra/runcmds: Cannot create child process: -40 (ZX_ERR_IO): failed to load executable from /boot/bin/mkdir\r\n[00003.581] 01103.03897> devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so'\r\n[00003.596] 01103.03897> devcoordinator: adding system driver 'magma_pdev_gpu' '/system/driver/libmsd_arm.so'\r\n[00003.608] 01103.03897> devcoordinator: adding system driver 'magma_pdev_gpu' '/system/driver/libmsd_vsl.so'\r\n[00003.631] 01103.03897> devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so'\r\n[00003.707] 01103.03897> devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so'\r\n[00003.715] 01103.03897> devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so'\r\n[00003.730] 00000.00000> INIT: cpu 2, calling hook 0xffffffff100850a0 (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2\r\n[00003.730] 00000.00000> ARM cpu 2: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000002 aff 0:0:0:2\r\n[00003.730] 00000.00000> entering scheduler on cpu 2\r\n[00003.757] 01103.03897> devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so'\r\n[00003.767] 00000.00000> INIT: cpu 3, calling hook 0xffffffff100850a0 (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2\r\n[00003.767] 00000.00000> ARM cpu 3: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000003 aff 0:0:0:3\r\n[00003.767] 00000.00000> entering scheduler on cpu 3\r\n[00003.775] 01103.03897> devcoordinator: adding system driver 'wlantapctl' '/system/driver/wlantap.so'\r\n[00003.798] 01103.03897> devcoordinator: adding system driver 'amlogic_video' '/system/driver/amlogic_video_decoder.so'\r\n[00003.804] 00000.00000> INIT: cpu 1, calling hook 0xffffffff100850a0 (arm_generic_timer_init_secondary_cpu) at level 0x7ffff, flags 0x2\r\n[00003.804] 00000.00000> ARM cpu 1: midr 0x431f0af1 'Cavium CN99XX r1p1' mpidr 0x80000001 aff 0:0:0:1\r\n[00003.804] 00000.00000> entering scheduler on cpu 1\r\n[00003.808] 01103.03897> devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so'\r\n[00003.849] 01103.03897> devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so'\r\n[00003.853] 01103.03897> devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so'\r\n[00003.868] 01103.03897> devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so'\r\n[00003.882] 01103.03897> devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so'\r\n[00003.886] 01103.03897> devcoordinator: adding system driver 'mt8167s_gpu' '/system/driver/libmsd_img_rgx_mtk.so'\r\n[00003.887] 01103.01116> devcoordinator: driver 'mt8167s_gpu' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'rtl88xx' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'qmi_usb' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'bt_host' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'brcmfmac' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'amlogic_video' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'bt_hci_intel' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'ath10k_pci' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'wlan' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'usb_video' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'magma_pdev_gpu' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'magma_pdev_gpu' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'ralink' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'bt_hci_fake' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'bt_hci_atheros' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'wlan' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'wlan' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'qmi_fake' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'bt_hog' added\r\n[00003.887] 01103.01116> devcoordinator: driver 'bt_hci_passthrough' added\r\n[00003.887] 01103.01116> devcoordinator: fallback driver 'usb_composite' is available\r\n[00003.887] 01103.01116> devcoordinator: driver 'usb_composite' added\r\n[00003.887] 01103.01116> devcoordinator: fallback driver 'intel_disp' is available\r\n[00003.887] 01103.01116> devcoordinator: driver 'intel_disp' added\r\n", "outputs": [ "output.fs" ], "results": { "bot_dimensions": [ { "key": "cores", "value": [ "8" ] }, { "key": "cpu", "value": [ "arm", "arm-64", "arm64", "arm64-64" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "none" ] }, { "key": "id", "value": [ "build384-a9--docker024" ] }, { "key": "inside_docker", "value": [ "1", "stock" ] }, { "key": "kvm", "value": [ "1" ] }, { "key": "machine_type", "value": [ "n1-highmem-8" ] }, { "key": "os", "value": [ "Debian", "Debian-9.4", "Linux" ] }, { "key": "pool", "value": [ "fuchsia.tests" ] }, { "key": "python", "value": [ "2.7.13" ] }, { "key": "server_version", "value": [ "4216-cf4a3cd" ] }, { "key": "ssd", "value": [ "1" ] }, { "key": "zone", "value": [ "us", "us-mtv", "us-mtv-chops", "us-mtv-chops-a", "us-mtv-chops-a-9" ] } ], "bot_id": "build384-a9--docker024", "bot_version": "c7f0c187ee2160053ce935aa86119b526086da1e165dea02547e2f5a35dc9e3f", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/linux-arm64", "version": "ga5vu7UTVMBmUswx0FrYIplu0N_bl93RS6AOsx07Ex0C" }, "packages": [ { "package_name": "fuchsia/tools/botanist/linux-arm64", "path": ".", "version": "rCqFxFUTs6GYygS6aiB2OnJWTVKfMclF0FLh56K6S9sC" }, { "package_name": "fuchsia/qemu/linux-arm64", "path": "qemu", "version": "b1b61a39e3ab0935cd030f27e01740578b04b967" } ] }, "completed_ts": "2019-03-26T17:05:29.804794", "costs_usd": [ 0.03771676817609634 ], "created_ts": "2019-03-26T17:00:47.266473", "duration": 227.863627910614, "exit_code": "-9", "failure": true, "modified_ts": "2019-03-26T17:05:29.804794", "name": "QEMU", "outputs_ref": { "isolated": "94641be74e6e3dc938f2463b5125106b38bed5f9", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "43d3c4033b319b11", "server_versions": [ "4216-cf4a3cd" ], "started_ts": "2019-03-26T17:01:06.557729", "state": "TIMED_OUT", "tags": [ "cpu:arm64", "kvm:1", "os:Debian", "pool:fuchsia.tests", "priority:200", "service_account:none", "swarming.pool.template:none", "swarming.pool.version:3f7a62dea449a91d9554cb660942fc89461b3c6a", "user:None" ], "task_id": "43d3c4033b319b10", "try_number": "1" } } }