eve-tot-chrome-pfq-informational failure |
|||
Issue descriptionLooks like ARC P specific issue. build: https://cros-goldeneye.corp.google.com/chromeos/healthmonitoring/buildDetails?buildbucketId=8927156445147951728 log: https://stainless.corp.google.com/browse/chromeos-autotest-results/267140341-chromeos-test/\ arc log: 2018-12-14T18:08:53.501469+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.secure_element@1.0::ISecureElement/eSE1' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:08:53.501497+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.secure_element@1.0::ISecureElement/eSE1 2018-12-14T18:08:53.501507+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.secure_element@1.0::ISecureElement/SIM1' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:08:53.501516+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.secure_element@1.0::ISecureElement/SIM1 2018-12-14T18:08:55.381651+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'start' for 'arc-dumpstatez' from pid: 41 (/system/bin/arc-bugreportd) 2018-12-14T18:08:55.381861+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: starting service 'arc-dumpstatez'... 2018-12-14T18:08:55.385647+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Created socket '/dev/socket/dumpstate', mode 660, user 2000, group 1007 2018-12-14T18:08:56.532262+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1429 exited with status 0 2018-12-14T18:08:56.533191+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1431 exited with status 0 2018-12-14T18:08:56.603626+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1442 exited with status 0 2018-12-14T18:08:56.603843+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1440 exited with status 0 2018-12-14T18:08:56.654036+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1449 exited with status 0 2018-12-14T18:08:56.654338+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1452 exited with status 0 2018-12-14T18:08:56.674079+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1457 exited with status 0 2018-12-14T18:08:56.675606+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1459 exited with status 0 2018-12-14T18:08:56.718992+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1470 exited with status 0 2018-12-14T18:08:56.719819+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1478 exited with status 0 2018-12-14T18:08:56.756609+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1484 exited with status 0 2018-12-14T18:08:56.756766+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1489 exited with status 0 2018-12-14T18:08:56.860713+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1500 exited with status 0 2018-12-14T18:08:56.861470+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1508 exited with status 0 2018-12-14T18:08:56.924265+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1534 exited with status 0 2018-12-14T18:08:56.924540+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1541 exited with status 0 2018-12-14T18:08:56.965901+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1547 exited with status 0 2018-12-14T18:08:56.966717+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1549 exited with status 0 2018-12-14T18:08:57.002251+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1553 exited with status 0 2018-12-14T18:08:57.003261+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1555 exited with status 0 2018-12-14T18:08:57.094506+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1570 exited with status 0 2018-12-14T18:08:57.095029+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1575 exited with status 0 2018-12-14T18:08:57.168069+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1582 exited with status 0 2018-12-14T18:08:57.168559+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1584 exited with status 0 2018-12-14T18:08:57.221769+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1592 exited with status 0 2018-12-14T18:08:57.221953+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1596 exited with status 0 2018-12-14T18:08:57.333294+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1624 exited with status 0 2018-12-14T18:08:57.333422+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Untracked pid 1626 exited with status 0 2018-12-14T18:08:57.625643+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: processing action (sys.sysctl.tcp_def_init_rwnd=*) from (/init.rc:730) 2018-12-14T18:08:57.724438+00:00 NOTICE arc-kmsg-logger[12520]: message repeated 7 times: [ <6>init: processing action (sys.sysctl.tcp_def_init_rwnd=*) from (/init.rc:730)] 2018-12-14T18:08:57.955701+00:00 NOTICE arc-kmsg-logger[12520]: <31>logd: logdr: UID=10000 GID=10000 PID=925 n tail=0 logMask=4 pid=0 start=0ns timeout=0ns 2018-12-14T18:09:03.062318+00:00 INFO arc-networkd[13000]: [INFO:router_finder.cc(57)] No IPv6 router found on iface eth0 2018-12-14T18:09:04.085487+00:00 NOTICE arc-kmsg-logger[12520]: <31>logd: logdr: UID=2000 GID=2000 PID=1868 n tail=0 logMask=19 pid=0 start=0ns timeout=0ns 2018-12-14T18:09:04.127399+00:00 NOTICE arc-kmsg-logger[12520]: <31>logd: logdr: UID=2000 GID=2000 PID=1870 n tail=0 logMask=4 pid=0 start=0ns timeout=0ns 2018-12-14T18:09:04.136127+00:00 NOTICE arc-kmsg-logger[12520]: <31>logd: logdr: UID=2000 GID=2000 PID=1872 n tail=0 logMask=20 pid=0 start=0ns timeout=0ns 2018-12-14T18:09:04.139110+00:00 NOTICE arc-kmsg-logger[12520]: <31>logd: logdr: UID=2000 GID=2000 PID=1874 n tail=0 logMask=2 pid=0 start=0ns timeout=0ns 2018-12-14T18:09:05.733694+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.dumpstate@1.0::IDumpstateDevice/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.733711+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.dumpstate@1.0::IDumpstateDevice/default 2018-12-14T18:09:05.964394+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.964411+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.964586+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.964602+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.964850+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.964866+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.965154+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.965170+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.965390+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.965404+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.965638+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.965655+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.965845+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.965894+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.966159+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.966176+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.966413+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.966445+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.966680+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.966697+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.966903+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.966920+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.967141+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.967157+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.967362+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.967378+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.967583+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.967600+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.967807+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.967821+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.968060+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.968077+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.968673+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.968726+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:05.968774+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'interface_start' for 'android.hardware.thermal@1.0::IThermal/default' from pid: 7 (/system/bin/hwservicemanager) 2018-12-14T18:09:05.968788+00:00 NOTICE arc-kmsg-logger[12520]: <3>init: Could not find service hosting interface android.hardware.thermal@1.0::IThermal/default 2018-12-14T18:09:08.096271+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Service 'arc-dumpstatez' (pid 1256) exited with status 0 2018-12-14T18:09:08.122059+00:00 NOTICE arc-kmsg-logger[12520]: <6>init: Received control message 'stop' for 'arc-dumpstatez' from pid: 41 (/system/bin/arc-bugreportd) 2018-12-14T18:09:08.191467+00:00 NOTICE arc-sysctl[15739]: Post-stop arc-sysctl 2018-12-14T18:09:08.192438+00:00 NOTICE arc-kmsg-logger[15741]: Post-stop arc-kmsg-logger 2018-12-14T18:09:08.192908+00:00 NOTICE arc-sensor[15743]: Post-stop arc-sensor 2018-12-14T18:09:08.193082+00:00 NOTICE arc-sensor[15743]: + find /sys/devices -path *cros-ec-ring* -name buffer 2018-12-14T18:09:08.193287+00:00 NOTICE arc-removable-media-write[15745]: Post-stop arc-removable-media-write 2018-12-14T18:09:08.193770+00:00 NOTICE arc-removable-media[15747]: Post-stop arc-removable-media 2018-12-14T18:09:08.196213+00:00 NOTICE arc-removable-media-default[15751]: Post-stop arc-removable-media-default 2018-12-14T18:09:08.196329+00:00 NOTICE arc-removable-media-read[15750]: Post-stop arc-removable-media-read 2018-12-14T18:09:08.197115+00:00 NOTICE arc-appfuse-provider[15754]: Post-stop arc-appfuse-provider 2018-12-14T18:09:08.201204+00:00 NOTICE arc-obb-mounter[15755]: Post-stop arc-obb-mounter 2018-12-14T18:09:08.211470+00:00 NOTICE arc-sysctl[15739]: vm.min_filelist_kbytes = 100000 2018-12-14T18:09:08.213232+00:00 NOTICE arc-network[15766]: Post-stop arc-network 2018-12-14T18:09:08.213473+00:00 WARNING arc-networkd[13002]: [WARNING:ip_helper.cc(95)] Read failed: exiting: Connection reset by peer 2018-12-14T18:09:08.224532+00:00 NOTICE arc-sensor[15743]: + ring_buffer_dir=/sys/devices/pci0000:00/0000:00:1f.0/PNP0C09:00/GOOG0004:00/cros-ec-ctl.1.auto/cros-ec-ring.0/iio:device3/buffer 2018-12-14T18:09:08.224546+00:00 NOTICE arc-sensor[15743]: + [ -d /sys/devices/pci0000:00/0000:00:1f.0/PNP0C09:00/GOOG0004:00/cros-ec-ctl.1.auto/cros-ec-ring.0/iio:device3/buffer ] 2018-12-14T18:09:08.224629+00:00 NOTICE arc-sensor[15743]: + echo 0 2018-12-14T18:09:08.243408+00:00 INFO arc-container[15771]: [INFO:run_oci.cc(875)] Sending signal 15 to container android-run_oci 2018-12-14T18:09:13.032071+00:00 INFO arc-container[15831]: [INFO:run_oci.cc(901)] Destroying container android-run_oci 2018-12-14T18:09:13.033115+00:00 INFO arc-container[15831]: [INFO:run_oci.cc(939)] Container android-run_oci destroyed 2018-12-14T18:09:13.042147+00:00 NOTICE arc-lifetime[15837]: Post-stop arc-lifetime 2018-12-14T18:09:13.042252+00:00 NOTICE arc-lifetime[15837]: + stop arc-sdcard 2018-12-14T18:09:13.044190+00:00 NOTICE arc-sdcard[15841]: Post-stop arc-sdcard 2018-12-14T18:09:13.044270+00:00 NOTICE arc-sdcard[15841]: + stop arc-sdcard-mount 2018-12-14T18:09:13.046542+00:00 NOTICE arc-sdcard[15841]: stop: Unknown instance: 2018-12-14T18:09:13.046831+00:00 NOTICE arc-sdcard[15841]: + find /sys/kernel/config/sdcardfs/ -mindepth 1 -maxdepth 1 -type d -not -name extensions -exec rmdir {} + 2018-12-14T18:09:13.539038+00:00 INFO arc-sdcard[15839]: [INFO:main.cc(69)] Starting /usr/sbin/arc-setup --mode=unmount-sdcard --log_tag=arc-sdcard 2018-12-14T18:09:13.539280+00:00 INFO arc-sdcard[15839]: [INFO:arc_setup.cc(818)] Running arc_mounter_->Umount(kDestDirectory)... 2018-12-14T18:09:13.551409+00:00 INFO arc-sdcard[15839]: message repeated 2 times: [ [INFO:arc_setup.cc(818)] Running arc_mounter_->Umount(kDestDirectory)...] 2018-12-14T18:09:13.557356+00:00 INFO arc-sdcard[15839]: [INFO:arc_setup.cc(821)] Unmount sdcard complete. 2018-12-14T18:09:13.557613+00:00 INFO arc-sdcard[15839]: [INFO:main.cc(74)] /usr/sbin/arc-setup --mode=unmount-sdcard --log_tag=arc-sdcard took 19ms 2018-12-14T18:09:13.560597+00:00 NOTICE arc-lifetime[15837]: arc-sdcard stop/waiting 2018-12-14T18:09:13.580590+00:00 INFO arc-lifetime[15835]: [INFO:main.cc(69)] Starting /usr/sbin/arc-setup --mode=stop --log_tag=arc-lifetime 2018-12-14T18:09:13.580920+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(226)] Running entry.Write(0, kBinfmtMiscUnregister, sizeof(kBinfmtMiscUnregister) - 1)... 2018-12-14T18:09:13.580985+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(226)] Running entry.Write(0, kBinfmtMiscUnregister, sizeof(kBinfmtMiscUnregister) - 1)... 2018-12-14T18:09:13.581092+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(221)] Ignoring failure: Failed to open /proc/sys/fs/binfmt_misc/arm64_dyn: No such file or directory 2018-12-14T18:09:13.581135+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(221)] Ignoring failure: Failed to open /proc/sys/fs/binfmt_misc/arm64_exe: No such file or directory 2018-12-14T18:09:13.588222+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1670)] Running arc_mounter_->UmountIfExists( arc_paths_->shared_mount_directory.Append("cache"))... 2018-12-14T18:09:13.588345+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1672)] Running arc_mounter_->UmountIfExists( arc_paths_->shared_mount_directory.Append("data"))... 2018-12-14T18:09:13.588418+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1674)] Running arc_mounter_->LoopUmountIfExists( arc_paths_->shared_mount_directory.Append("demo_apps"))... 2018-12-14T18:09:13.588464+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1675)] Running arc_mounter_->UmountIfExists(arc_paths_->adbd_mount_directory)... 2018-12-14T18:09:13.594273+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1676)] Running arc_mounter_->UmountIfExists(arc_paths_->media_dest_directory)... 2018-12-14T18:09:13.601493+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1678)] Running arc_mounter_->UmountIfExists(arc_paths_->media_dest_default_directory)... 2018-12-14T18:09:13.610232+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1680)] Running arc_mounter_->UmountIfExists(arc_paths_->media_dest_read_directory)... 2018-12-14T18:09:13.621708+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1682)] Running arc_mounter_->UmountIfExists(arc_paths_->media_dest_write_directory)... 2018-12-14T18:09:13.626054+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1684)] Running arc_mounter_->UmountIfExists(arc_paths_->media_mount_directory)... 2018-12-14T18:09:13.631194+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1685)] Running arc_mounter_->Umount(arc_paths_->sdcard_mount_directory)... 2018-12-14T18:09:13.640116+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1687)] Running arc_mounter_->UmountIfExists(arc_paths_->shared_mount_directory)... 2018-12-14T18:09:13.640202+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1688)] Running arc_mounter_->Umount(arc_paths_->obb_mount_directory)... 2018-12-14T18:09:13.649051+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1689)] Running arc_mounter_->Umount(arc_paths_->oem_mount_directory)... 2018-12-14T18:09:13.658192+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1691)] Running arc_mounter_->UmountIfExists(arc_paths_->android_mutable_source)... 2018-12-14T18:09:13.664191+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1693)] Running arc_mounter_->UmountIfExists( arc_paths_->debugfs_directory.Append("sync"))... 2018-12-14T18:09:13.671158+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1695)] Running arc_mounter_->UmountIfExists( arc_paths_->debugfs_directory.Append("tracing"))... 2018-12-14T18:09:13.677120+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1698)] Running arc_mounter_->UmountIfExists(arc_paths_->binfmt_misc_directory)... 2018-12-14T18:09:13.677165+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1701)] Running arc_mounter_->UmountIfExists(arc_paths_->android_rootfs_directory.Append( arc_paths_->system_lib_arm_directory_relative))... 2018-12-14T18:09:13.677247+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1708)] Running base::DeleteFile(base::FilePath("/run/arc/bugreport/pipe"), false )... 2018-12-14T18:09:13.677287+00:00 INFO arc-lifetime[15835]: [INFO:arc_setup.cc(1715)] Running base::DeleteFile(arc_paths_->android_kmsg_fifo, false )... 2018-12-14T18:09:13.677324+00:00 INFO arc-lifetime[15835]: [INFO:main.cc(74)] /usr/sbin/arc-setup --mode=stop --log_tag=arc-lifetime took 98ms
,
Dec 18
Update: eve-tot-chrome-pfq-informational just had a green run. Will keep monitoring it to see if this issue is really gone.
,
Dec 18
,
Dec 18
Took a look at logs at comment #1, and confirmed org.chromium.arc.intent_helper process was up and running. I believe this is an issue on the test side. A fix will be uploaded shortly.
,
Dec 19
The following revision refers to this bug: https://chromium.googlesource.com/chromiumos/third_party/autotest/+/ea5b8b66610c1d3b3c190a4d87ad724628610a8d commit ea5b8b66610c1d3b3c190a4d87ad724628610a8d Author: Yusuke Sato <yusukes@google.com> Date: Wed Dec 19 09:12:47 2018 vm_sanity: Make arc.wait_for_android_process call less flaky Previously, the test was like the following: if is_arc_available: arc.wait_for_android_process('org.chromium.arc.intent_helper') arc.wait_for_adb_ready() This worked, but was sometimes flaky. Since wait_for_android_process() also depends on ADB and internally calls wait_for_adb_ready() too, the arc.wait_for_android_process('org.chromium.arc.intent_helper') line had to wait for both ADB daemon and org.chromium.arc.intent_helper process to start with a single unified timeout. This CL changes vm_sanity.py so it calls arc.wait_for_adb_ready() first, with its own timeout. This will allow wait_for_android_process() to wait only for the org.chromium.arc.intent_helper process making the VMSanity test less likely to timeout. BUG= 915782 TEST=test_that nocturne login_VMSanity Change-Id: Ia100aa25e80983a23b8b7be2e1e4e84d09787b46 Reviewed-on: https://chromium-review.googlesource.com/1382801 Commit-Ready: Yusuke Sato <yusukes@chromium.org> Tested-by: Yusuke Sato <yusukes@chromium.org> Reviewed-by: Achuith Bhandarkar <achuith@chromium.org> Reviewed-by: Christopher Morin <cmtm@google.com> [modify] https://crrev.com/ea5b8b66610c1d3b3c190a4d87ad724628610a8d/client/bin/vm_sanity.py
,
Dec 19
I'd take comment #4 back. Now I think this was likely caused by b/121102309. Since the bug was already fixed, I'd close this one too. The CL at comment #5 has made login_VMSanity cleaner anyways, so I'd keep it. |
|||
►
Sign in to add a comment |
|||
Comment 1 by x...@chromium.org
, Dec 17