Kernel crash on Guado after continuously joining/leaving meeting with muting/unmuting camera operations during meeting |
||||||||||||||||||||||
Issue descriptionGuado: R61-9765.8.0. Steps: ------ 1. Join meeting. 2. keep meet on. There are 12 participants in meeting. 11 are from Guado. 3. After in meeting for about 4-5 hours, chrome crashes on one of Guado. Another 10 Guado do not report kernel crash. What is the expected result? =========================== no crash. What happens instead? Please use labels and text to provide additional information. ============================================================= 2017-08-05T01:21:24.856306-07:00 WARNING kernel: [32889.304980] ------------[ cut here ]------------ 2017-08-05T01:21:24.856335-07:00 WARNING kernel: [32889.305011] WARNING: CPU: 2 PID: 0 at /mnt/host/source/src/third_party/kernel/v3.14/drivers/gpu/drm/i915/intel_display.c:9084 intel_check_page_flip+0x98/0xbd() 2017-08-05T01:21:24.856342-07:00 NOTICE kernel: [32889.305041] Kicking stuck page flip: queued at 1967861, now 1967866 2017-08-05T01:21:24.856370-07:00 NOTICE kernel: [32889.305054] Modules linked in: evdi uinput rfcomm joydev uvcvideo videobuf2_vmalloc hid_multitouch i2c_dev zram cmac memc_x86 x86_pkg_temp_thermal snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic snd_soc_sst_acpi snd_hda_intel snd_hda_controller snd_hda_codec fuse snd_usb_audio snd_usbmidi_lib snd_hwdep ip6table_filter snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq snd_seq_device ip6_tables iwlmvm r8169 mii iwlwifi iwl7000_mac80211 cfg80211 btusb btbcm btintel bluetooth 2017-08-05T01:21:24.856375-07:00 NOTICE kernel: [32889.305249] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 3.14.0 #1 2017-08-05T01:21:24.856380-07:00 NOTICE kernel: [32889.305263] Hardware name: GOOGLE Guado, BIOS Google_Guado.6301.108.4 04/01/2015 2017-08-05T01:21:24.856384-07:00 NOTICE kernel: [32889.305280] 0000000000000000 565c3ecb42f591dc ffff88017ed03d90 ffffffff8dc1b43c 2017-08-05T01:21:24.856388-07:00 NOTICE kernel: [32889.305303] ffff88017ed03dd8 ffff88017ed03dc8 ffffffff8d65d7e8 ffffffff8d9627f2 2017-08-05T01:21:24.856391-07:00 NOTICE kernel: [32889.305326] ffff880178eb1800 ffff880178e60000 ffff880178eb1a08 0000000000000000 2017-08-05T01:21:24.856395-07:00 NOTICE kernel: [32889.305350] Call Trace: 2017-08-05T01:21:24.856398-07:00 NOTICE kernel: [32889.305358] <IRQ> [<ffffffff8dc1b43c>] dump_stack+0x4d/0x6f 2017-08-05T01:21:24.856401-07:00 NOTICE kernel: [32889.305387] [<ffffffff8d65d7e8>] warn_slowpath_common+0x7f/0x98 2017-08-05T01:21:24.856404-07:00 NOTICE kernel: [32889.305406] [<ffffffff8d9627f2>] ? intel_check_page_flip+0x98/0xbd 2017-08-05T01:21:24.856408-07:00 NOTICE kernel: [32889.305424] [<ffffffff8d65d858>] warn_slowpath_fmt+0x57/0x73 2017-08-05T01:21:24.856411-07:00 NOTICE kernel: [32889.305441] [<ffffffff8d9552bc>] ? __intel_pageflip_stall_check+0xc9/0xf7 2017-08-05T01:21:24.856414-07:00 NOTICE kernel: [32889.305460] [<ffffffff8d9627f2>] intel_check_page_flip+0x98/0xbd 2017-08-05T01:21:24.856418-07:00 NOTICE kernel: [32889.305479] [<ffffffff8d93b8f5>] gen8_irq_handler+0x3e1/0x53c 2017-08-05T01:21:24.856422-07:00 NOTICE kernel: [32889.305499] [<ffffffff8d6a0cb7>] handle_irq_event_percpu+0x78/0x1aa 2017-08-05T01:21:24.856425-07:00 NOTICE kernel: [32889.305520] [<ffffffff8d6a0e27>] handle_irq_event+0x3e/0x5b 2017-08-05T01:21:24.856429-07:00 NOTICE kernel: [32889.305540] [<ffffffff8d6a369b>] handle_edge_irq+0xa4/0xbd 2017-08-05T01:21:24.856433-07:00 NOTICE kernel: [32889.305559] [<ffffffff8d604427>] handle_irq+0x118/0x120 2017-08-05T01:21:24.856436-07:00 NOTICE kernel: [32889.305576] [<ffffffff8dc2590d>] do_IRQ+0x4d/0xc2 2017-08-05T01:21:24.856439-07:00 NOTICE kernel: [32889.305593] [<ffffffff8dc231aa>] common_interrupt+0x6a/0x6a 2017-08-05T01:21:24.856442-07:00 NOTICE kernel: [32889.305606] <EOI> [<ffffffff8dab9fa8>] ? cpuidle_enter_state+0x44/0x99 2017-08-05T01:21:24.856446-07:00 NOTICE kernel: [32889.305638] [<ffffffff8daba0e7>] cpuidle_idle_call+0xea/0x14b 2017-08-05T01:21:24.856449-07:00 NOTICE kernel: [32889.305658] [<ffffffff8d60b406>] arch_cpu_idle+0xe/0x1d 2017-08-05T01:21:24.856452-07:00 NOTICE kernel: [32889.305680] [<ffffffff8d6a04c0>] cpu_startup_entry+0x16d/0x1cb 2017-08-05T01:21:24.856455-07:00 NOTICE kernel: [32889.305703] [<ffffffff8d6294c1>] start_secondary+0x1fa/0x213 2017-08-05T01:21:24.856458-07:00 WARNING kernel: [32889.305721] ---[ end trace 8e9d48625ab303db ]--- Log file ========= uploaded to google drive.
,
Aug 6 2017
,
Aug 6 2017
,
Aug 14 2017
,
Aug 15 2017
Min, I think this might be a different set of debug-logs, since they show a different kernel warning and have a different timestamp. Do you still have a copy of the matching debug-logs tarball?
,
Aug 16 2017
Steve, I found log. Uploading tar file to gdriver: https://drive.google.com/corp/drive/u/1/folders/0B8sYeJbsz9zKcVl5cDVDendKZGM After you untar it, you will get all files under /var/log/ and /var/spool/crash. ================= mzhuo@minzhuo2:~/chromiumos/chroot/tmp$ cd test_that_results_iJrXAG/results-1-enterprise_CFM_Sanity/sysinfo/var/ mzhuo@minzhuo2:~/chromiumos/chroot/tmp/test_that_results_iJrXAG/results-1-enterprise_CFM_Sanity/sysinfo/var$ ls -altr total 16 drwxr-xr-x 3 mzhuo eng 4096 Aug 7 21:59 spool drwxr-xr-x 4 mzhuo eng 4096 Aug 7 21:59 . drwxr-xr-x 11 mzhuo eng 4096 Aug 7 21:59 log drwxr-xr-x 4 mzhuo eng 4096 Aug 7 21:59 .. mzhuo@minzhuo2:~/chromiumos/chroot/tmp/test_that_results_iJrXAG/results-1-enterprise_CFM_Sanity/sysinfo/var$ cd log mzhuo@minzhuo2:~/chromiumos/chroot/tmp/test_that_results_iJrXAG/results-1-enterprise_CFM_Sanity/sysinfo/var/log$ ls -altr total 16060 -rw-r--r-- 1 mzhuo eng 68 Jul 12 12:36 vpd_2.0.txt -rw-r--r-- 1 mzhuo eng 1680 Aug 1 10:41 clobber.log -rw-r--r-- 1 mzhuo eng 0 Aug 4 16:13 authpolicy.3.log -rw-r--r-- 1 mzhuo eng 18118 Aug 4 16:13 tlsdate.3.log -rw-r--r-- 1 mzhuo eng 1190387 Aug 4 21:59 atrus.3.log -rw-r--r-- 1 mzhuo eng 393483 Aug 4 21:59 net.3.log -rw-r--r-- 1 mzhuo eng 36917 Aug 4 21:59 secure.3 -rw-r--r-- 1 mzhuo eng 1465907 Aug 4 21:59 messages.3 -rw-r--r-- 1 mzhuo eng 0 Aug 4 21:59 authpolicy.2.log -rw-r--r-- 1 mzhuo eng 3969 Aug 5 16:13 tlsdate.2.log -rw-r--r-- 1 mzhuo eng 3579613 Aug 5 22:00 atrus.2.log -rw-r--r-- 1 mzhuo eng 343047 Aug 5 22:00 net.2.log -rw-r--r-- 1 mzhuo eng 154912 Aug 5 22:01 messages.2 -rw-r--r-- 1 mzhuo eng 41223 Aug 5 22:01 secure.2 -rw-r--r-- 1 mzhuo eng 0 Aug 5 22:01 authpolicy.1.log -rw-r--r-- 1 mzhuo eng 112966 Aug 6 01:11 multimedia_xmlrpc_server.log -rw-r--r-- 1 mzhuo eng 3971 Aug 6 16:13 tlsdate.1.log -rw-r--r-- 1 mzhuo eng 406825 Aug 6 22:02 net.1.log -rw-r--r-- 1 mzhuo eng 3586993 Aug 6 22:02 atrus.1.log -rw-r--r-- 1 mzhuo eng 28027 Aug 6 22:03 secure.1 -rw-r--r-- 1 mzhuo eng 157303 Aug 6 22:03 messages.1 -rw------- 1 mzhuo eng 64 Aug 7 12:20 tallylog -rw-r--r-- 1 mzhuo eng 2192 Aug 7 18:59 shutdown_force_kill_processes -rw-r--r-- 1 mzhuo eng 1237 Aug 7 18:59 mount_options.log -rw-r--r-- 1 mzhuo eng 839 Aug 7 18:59 mount-encrypted.log -rw-r--r-- 1 mzhuo eng 0 Aug 7 18:59 powerd.out -rw-r--r-- 1 mzhuo eng 0 Aug 7 18:59 laptopmode.log -rw-r--r-- 1 mzhuo eng 0 Aug 7 18:59 boot.log -rw-r--r-- 1 mzhuo eng 0 Aug 7 18:59 authpolicy.log -rw-r--r-- 1 mzhuo eng 10569 Aug 7 18:59 eventlog.txt -rw-r--r-- 1 mzhuo eng 1724 Aug 7 18:59 bios_times.txt -rw-r--r-- 1 mzhuo eng 6381 Aug 7 18:59 bios_info.txt -rw-r--r-- 1 mzhuo eng 10468 Aug 7 18:59 storage_info.txt -rw-r--r-- 1 mzhuo eng 10562 Aug 7 18:59 tlsdate.log -rw-r--r-- 1 mzhuo eng 95 Aug 7 18:59 platform_info.txt -rw-r--r-- 1 mzhuo eng 850 Aug 7 18:59 memory_spd_info.txt -rw-r--r-- 1 mzhuo eng 43520 Aug 7 19:00 debug_vboot_noisy.log -rw-r--r-- 1 mzhuo eng 6330 Aug 7 21:27 update_engine.log -rw-r--r-- 1 mzhuo eng 2 Aug 7 21:27 devserver_hostlog -rw-r--r-- 1 mzhuo eng 426060 Aug 7 21:58 net.log -rw-r--r-- 1 mzhuo eng 122969 Aug 7 21:59 secure -rw-r--r-- 1 mzhuo eng 292 Aug 7 21:59 lastlog -rw-r--r-- 1 mzhuo eng 3574843 Aug 7 21:59 atrus.log -rw-r--r-- 1 mzhuo eng 569897 Aug 7 21:59 messages drwxr-xr-x 4 mzhuo eng 4096 Aug 7 21:59 .. drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 displaylink drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 asan drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 update_engine drwxr-xr-x 6 mzhuo eng 4096 Aug 7 21:59 metrics drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 ui drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 vmlog drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 recover_duts drwxr-xr-x 3 mzhuo eng 4096 Aug 7 21:59 chrome drwxr-xr-x 2 mzhuo eng 4096 Aug 7 21:59 power_manager drwxr-xr-x 11 mzhuo eng 4096 Aug 7 21:59 . mzhuo@minzhuo2:~/chromiumos/chroot/tmp/test_that_results_iJrXAG/results-1-enterprise_CFM_Sanity/sysinfo/var/log$ grep -r 'intel_display.c:9084' . ./messages.2:2017-08-05T01:21:24.856335-07:00 WARNING kernel: [32889.305011] WARNING: CPU: 2 PID: 0 at /mnt/host/source/src/third_party/kernel/v3.14/drivers/gpu/drm/i915/intel_display.c:9084 intel_check_page_flip+0x98/0xbd()
,
Aug 23 2017
,
Oct 2 2017
Hit again on 2 Guado running R61-9765.72.0. 100.123.174.2 ================================ 2017-10-02T01:13:58.478334-07:00 WARNING kernel: [104338.811251] ------------[ cut here ]------------ 2017-10-02T01:13:58.478345-07:00 WARNING kernel: [104338.811271] WARNING: CPU: 0 PID: 32678 at /mnt/host/source/src/third_party/kernel/v3.14/drivers/gpu/drm/i915/intel_display.c:9084 intel_check_page_flip+0x98/0xbd() 2017-10-02T01:13:58.478347-07:00 NOTICE kernel: [104338.811281] Kicking stuck page flip: queued at 6249831, now 6249836 2017-10-02T01:13:58.478348-07:00 NOTICE kernel: [104338.811285] Modules linked in: evdi uinput rfcomm i2c_dev uvcvideo videobuf2_vmalloc hid_multitouch joydev snd_usb_audio snd_usbmidi_lib ip6t_REJECT memc_x86 snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic snd_soc_sst_acpi snd_hda_intel snd_hda_controller snd_hda_codec fuse x86_pkg_temp_thermal zram snd_hwdep ip6table_filter ip6_tables snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq snd_seq_device btusb btbcm btintel bluetooth iwlmvm r8169 mii iwlwifi iwl7000_mac80211 cfg80211 2017-10-02T01:13:58.478349-07:00 NOTICE kernel: [104338.811356] CPU: 0 PID: 32678 Comm: drishti_calcula Not tainted 3.14.0 #4 2017-10-02T01:13:58.478350-07:00 NOTICE kernel: [104338.811362] Hardware name: GOOGLE Guado, BIOS Google_Guado.6301.108.4 04/01/2015 2017-10-02T01:13:58.478351-07:00 NOTICE kernel: [104338.811367] 0000000000000000 00000000c3dd8e64 ffff88017ec03d90 ffffffff8ea1b75c 2017-10-02T01:13:58.478364-07:00 NOTICE kernel: [104338.811377] ffff88017ec03dd8 ffff88017ec03dc8 ffffffff8e45d7e8 ffffffff8e762824 2017-10-02T01:13:58.478365-07:00 NOTICE kernel: [104338.811385] ffff880178eb1800 ffff880178e60000 ffff880178eb1a08 0000000000000000 2017-10-02T01:13:58.478366-07:00 NOTICE kernel: [104338.811394] Call Trace: 2017-10-02T01:13:58.478367-07:00 NOTICE kernel: [104338.811398] <IRQ> [<ffffffff8ea1b75c>] dump_stack+0x4d/0x6f 2017-10-02T01:13:58.478368-07:00 NOTICE kernel: [104338.811409] [<ffffffff8e45d7e8>] warn_slowpath_common+0x7f/0x98 2017-10-02T01:13:58.478369-07:00 NOTICE kernel: [104338.811416] [<ffffffff8e762824>] ? intel_check_page_flip+0x98/0xbd 2017-10-02T01:13:58.478370-07:00 NOTICE kernel: [104338.811423] [<ffffffff8e45d858>] warn_slowpath_fmt+0x57/0x73 2017-10-02T01:13:58.478378-07:00 NOTICE kernel: [104338.811429] [<ffffffff8e7552ee>] ? __intel_pageflip_stall_check+0xc9/0xf7 2017-10-02T01:13:58.478379-07:00 NOTICE kernel: [104338.811437] [<ffffffff8e762824>] intel_check_page_flip+0x98/0xbd 2017-10-02T01:13:58.478380-07:00 NOTICE kernel: [104338.811444] [<ffffffff8e73b927>] gen8_irq_handler+0x3e1/0x53c 2017-10-02T01:13:58.478381-07:00 NOTICE kernel: [104338.811451] [<ffffffff8e4a0ca5>] handle_irq_event_percpu+0x78/0x1aa 2017-10-02T01:13:58.478381-07:00 NOTICE kernel: [104338.811458] [<ffffffff8e4a0e15>] handle_irq_event+0x3e/0x5b 2017-10-02T01:13:58.478382-07:00 NOTICE kernel: [104338.811465] [<ffffffff8e4a3689>] handle_edge_irq+0xa4/0xbd 2017-10-02T01:13:58.478390-07:00 NOTICE kernel: [104338.811471] [<ffffffff8e404427>] handle_irq+0x118/0x120 2017-10-02T01:13:58.478391-07:00 NOTICE kernel: [104338.811477] [<ffffffff8ea25c4d>] do_IRQ+0x4d/0xc2 2017-10-02T01:13:58.478392-07:00 NOTICE kernel: [104338.811484] [<ffffffff8ea234ea>] common_interrupt+0x6a/0x6a 2017-10-02T01:13:58.478393-07:00 NOTICE kernel: [104338.811489] <EOI> 2017-10-02T01:13:58.478394-07:00 WARNING kernel: [104338.811492] ---[ end trace ee446cb633a0efe6 ]--- 2017-10-02T01:13:58.575043-07:00 INFO crash_reporter[3800]: Processing kernel warning: always collect from developer builds 2017-10-02T01:13:58.576050-07:00 INFO crash_reporter[3800]: Stored kernel warning into /var/spool/crash/kernel_warning.20171002.011358.0.kcrash 100.123.173.71 ================== 2017-10-01T19:25:51.179661-07:00 WARNING kernel: [ 6406.969505] ------------[ cut here ]------------ 2017-10-01T19:25:51.179670-07:00 WARNING kernel: [ 6406.969522] WARNING: CPU: 0 PID: 0 at /mnt/host/source/src/third_party/kernel/v3.14/drivers/gpu/drm/i915/intel_display.c:9084 intel_check_page_flip+0x98/0xbd() 2017-10-01T19:25:51.179671-07:00 NOTICE kernel: [ 6406.969531] Kicking stuck page flip: queued at 383491, now 383496 2017-10-01T19:25:51.179672-07:00 NOTICE kernel: [ 6406.969535] Modules linked in: evdi uinput rfcomm i2c_dev ip6t_REJECT snd_hda_codec_realtek zram memc_x86 snd_hda_codec_generic x86_pkg_temp_thermal snd_hda_codec_hdmi snd_soc_sst_acpi snd_hda_intel snd_hda_controller snd_hda_codec fuse joydev hid_multitouch uvcvideo videobuf2_vmalloc snd_usb_audio snd_usbmidi_lib snd_hwdep ip6table_filter ip6_tables snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq snd_seq_device btusb btbcm btintel bluetooth iwlmvm r8169 mii iwlwifi iwl7000_mac80211 cfg80211 2017-10-01T19:25:51.179802-07:00 NOTICE kernel: [ 6406.969591] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 3.14.0 #5 2017-10-01T19:25:51.179805-07:00 NOTICE kernel: [ 6406.969595] Hardware name: GOOGLE Guado, BIOS Google_Guado.6301.108.4 04/01/2015 2017-10-01T19:25:51.179806-07:00 NOTICE kernel: [ 6406.969600] 0000000000000000 ce2d9b84238ee69e ffff88017ec03d90 ffffffff9fc1b76c 2017-10-01T19:25:51.179807-07:00 NOTICE kernel: [ 6406.969608] ffff88017ec03dd8 ffff88017ec03dc8 ffffffff9f65d7e8 ffffffff9f962824 2017-10-01T19:25:51.179808-07:00 NOTICE kernel: [ 6406.969615] ffff880178eb1800 ffff880178e60000 ffff880178eb1a08 0000000000000000 2017-10-01T19:25:51.179808-07:00 NOTICE kernel: [ 6406.969622] Call Trace: 2017-10-01T19:25:51.179809-07:00 NOTICE kernel: [ 6406.969625] <IRQ> [<ffffffff9fc1b76c>] dump_stack+0x4d/0x6f 2017-10-01T19:25:51.179810-07:00 NOTICE kernel: [ 6406.969636] [<ffffffff9f65d7e8>] warn_slowpath_common+0x7f/0x98 2017-10-01T19:25:51.179811-07:00 NOTICE kernel: [ 6406.969643] [<ffffffff9f962824>] ? intel_check_page_flip+0x98/0xbd 2017-10-01T19:25:51.179812-07:00 NOTICE kernel: [ 6406.969648] [<ffffffff9f65d858>] warn_slowpath_fmt+0x57/0x73 2017-10-01T19:25:51.179813-07:00 NOTICE kernel: [ 6406.969654] [<ffffffff9f9552ee>] ? __intel_pageflip_stall_check+0xc9/0xf7 2017-10-01T19:25:51.179814-07:00 NOTICE kernel: [ 6406.969659] [<ffffffff9f962824>] intel_check_page_flip+0x98/0xbd 2017-10-01T19:25:51.179815-07:00 NOTICE kernel: [ 6406.969665] [<ffffffff9f93b927>] gen8_irq_handler+0x3e1/0x53c 2017-10-01T19:25:51.179815-07:00 NOTICE kernel: [ 6406.969671] [<ffffffff9f6a0ca5>] handle_irq_event_percpu+0x78/0x1aa 2017-10-01T19:25:51.179816-07:00 NOTICE kernel: [ 6406.969677] [<ffffffff9f6a0e15>] handle_irq_event+0x3e/0x5b 2017-10-01T19:25:51.179817-07:00 NOTICE kernel: [ 6406.969682] [<ffffffff9f6a3689>] handle_edge_irq+0xa4/0xbd 2017-10-01T19:25:51.179818-07:00 NOTICE kernel: [ 6406.969687] [<ffffffff9f604427>] handle_irq+0x118/0x120 2017-10-01T19:25:51.179818-07:00 NOTICE kernel: [ 6406.969692] [<ffffffff9fc25c4d>] do_IRQ+0x4d/0xc2 2017-10-01T19:25:51.179819-07:00 NOTICE kernel: [ 6406.969697] [<ffffffff9fc234ea>] common_interrupt+0x6a/0x6a 2017-10-01T19:25:51.179820-07:00 NOTICE kernel: [ 6406.969701] <EOI> [<ffffffff9f68d9e8>] ? set_next_entity+0x44/0x9b 2017-10-01T19:25:51.179821-07:00 NOTICE kernel: [ 6406.969708] [<ffffffff9faba2ac>] ? cpuidle_enter_state+0x44/0x99 2017-10-01T19:25:51.179822-07:00 NOTICE kernel: [ 6406.969713] [<ffffffff9faba2a8>] ? cpuidle_enter_state+0x40/0x99 2017-10-01T19:25:51.179822-07:00 NOTICE kernel: [ 6406.969718] [<ffffffff9faba3eb>] cpuidle_idle_call+0xea/0x14b 2017-10-01T19:25:51.179823-07:00 NOTICE kernel: [ 6406.969723] [<ffffffff9f60b406>] arch_cpu_idle+0xe/0x1d 2017-10-01T19:25:51.179824-07:00 NOTICE kernel: [ 6406.969728] [<ffffffff9f6a04ae>] cpu_startup_entry+0x16d/0x1cb 2017-10-01T19:25:51.179824-07:00 NOTICE kernel: [ 6406.969734] [<ffffffff9fc174bb>] rest_init+0x7f/0x82 2017-10-01T19:25:51.179825-07:00 NOTICE kernel: [ 6406.969739] [<ffffffffa02ffdac>] start_kernel+0x414/0x435 2017-10-01T19:25:51.179826-07:00 NOTICE kernel: [ 6406.969744] [<ffffffffa02ff488>] x86_64_start_reservations+0x2a/0x2c 2017-10-01T19:25:51.179827-07:00 NOTICE kernel: [ 6406.969749] [<ffffffffa02ff59f>] x86_64_start_kernel+0x115/0x118 2017-10-01T19:25:51.179828-07:00 WARNING kernel: [ 6406.969754] ---[ end trace d3bebfa0d006a368 ]--- 2017-10-01T19:25:51.191161-07:00 INFO crash_reporter[18497]: Processing kernel warning: always collect from developer builds 2017-10-01T19:25:51.191525-07:00 INFO crash_reporter[18497]: Stored kernel warning into /var/spool/crash/kernel_warning.20171001.192551.0.kcrash
,
Oct 25 2017
Happened again on 100.107.146.14 ( Guado + Kepler) running R61-9765.86.0. It happen 05:32:42 INFO | autoserv| 100.107.146.14: Start Test: 51, meets: 51, muted/unmuted/volume change from Hotrod: 526 05:32:42 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:32:43 INFO | autoserv| check device 2bd9:0011 05:32:43 INFO | autoserv| check device 18d1:8001 05:32:43 INFO | autoserv| check device 17e9:016b 05:32:43 INFO | autoserv| check device 266e:0110 05:32:45 INFO | autoserv| 100.107.146.14: meet uqt-gegm-cyj joined 05:32:45 INFO | autoserv| Checking devices after joining meeting 05:32:45 INFO | autoserv| Check video on 100.107.146.14 for meet uqt-gegm-cyj:video should not be muted upon joining meeting 05:32:45 INFO | autoserv| Checking camera 05:32:46 INFO | autoserv| Checking speaker 05:32:46 INFO | autoserv| Hotrod volume is 60 for Bluestreak 100.107.146.14 05:32:46 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 60, cras: 60 05:32:46 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:32:47 INFO | autoserv| check device 2bd9:0011 05:32:47 INFO | autoserv| check device 18d1:8001 05:32:47 INFO | autoserv| check device 17e9:016b 05:32:47 INFO | autoserv| check device 266e:0110 05:33:24 INFO | autoserv| Checking periphreal devices during meeting 05:33:25 INFO | autoserv| Hotrod volume is 60 for Bluestreak 100.107.146.14 05:33:26 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 60, cras: 60 05:33:26 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:33:27 INFO | autoserv| check device 2bd9:0011 05:33:27 INFO | autoserv| check device 18d1:8001 05:33:27 INFO | autoserv| check device 17e9:016b 05:33:27 INFO | autoserv| check device 266e:0110 05:33:27 INFO | autoserv| Start to mute camera for Bluestreak 100.107.146.14 05:33:32 INFO | autoserv| Start to mute microphone for Bluestreak 100.107.146.14 05:33:37 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:33:42 INFO | autoserv| Checking periphreal devices during meeting 05:33:44 INFO | autoserv| Hotrod volume is 46 for Bluestreak 100.107.146.14 05:33:44 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 46, cras: 46 05:34:23 INFO | autoserv| Checking periphreal devices during meeting 05:34:24 INFO | autoserv| Hotrod volume is 46 for Bluestreak 100.107.146.14 05:34:24 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 46, cras: 46 05:34:24 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:34:26 INFO | autoserv| check device 2bd9:0011 05:34:26 INFO | autoserv| check device 18d1:8001 05:34:26 INFO | autoserv| check device 17e9:016b 05:34:26 INFO | autoserv| check device 266e:0110 05:34:26 INFO | autoserv| Start to unmute camera for Bluestreak 100.107.146.14 05:34:31 INFO | autoserv| Start to unmute microphone for Bluestreak 100.107.146.14 05:34:36 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:34:42 INFO | autoserv| Checking periphreal devices during meeting 05:34:43 INFO | autoserv| Hotrod volume is 91 for Bluestreak 100.107.146.14 05:34:43 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 91, cras: 91 05:35:24 INFO | autoserv| Checking periphreal devices during meeting 05:35:26 INFO | autoserv| Hotrod volume is 91 for Bluestreak 100.107.146.14 05:35:26 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 91, cras: 91 05:35:26 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:35:27 INFO | autoserv| check device 2bd9:0011 05:35:27 INFO | autoserv| check device 18d1:8001 05:35:27 INFO | autoserv| check device 17e9:016b 05:35:27 INFO | autoserv| check device 266e:0110 05:35:27 INFO | autoserv| Start to mute camera for Bluestreak 100.107.146.14 05:35:32 INFO | autoserv| Start to mute microphone for Bluestreak 100.107.146.14 05:35:37 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:35:43 INFO | autoserv| Checking periphreal devices during meeting 05:35:44 INFO | autoserv| Hotrod volume is 55 for Bluestreak 100.107.146.14 05:35:44 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 55, cras: 55 05:36:24 INFO | autoserv| Checking periphreal devices during meeting 05:36:25 INFO | autoserv| Hotrod volume is 55 for Bluestreak 100.107.146.14 05:36:25 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 55, cras: 55 05:36:25 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:36:27 INFO | autoserv| check device 2bd9:0011 05:36:27 INFO | autoserv| check device 18d1:8001 05:36:27 INFO | autoserv| check device 17e9:016b 05:36:27 INFO | autoserv| check device 266e:0110 05:36:27 INFO | autoserv| Start to unmute camera for Bluestreak 100.107.146.14 05:36:32 INFO | autoserv| Start to unmute microphone for Bluestreak 100.107.146.14 05:36:37 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:36:42 INFO | autoserv| Checking periphreal devices during meeting 05:36:44 INFO | autoserv| Hotrod volume is 32 for Bluestreak 100.107.146.14 05:36:44 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 32, cras: 32 2017-10-25T03:37:06.982720-07:00 WARNING kernel: [45305.939666] ------------[ cut here ]------------ 2017-10-25T03:37:06.982748-07:00 WARNING kernel: [45305.939697] WARNING: CPU: 2 PID: 0 at /mnt/host/source/src/third_party/kernel/v3.14/drivers/gpu/drm/i915/intel_display.c:9084 intel_check_page_flip+0x98/0xbd() 2017-10-25T03:37:06.982753-07:00 NOTICE kernel: [45305.939725] Kicking stuck page flip: queued at 1356641, now 1356646 2017-10-25T03:37:06.982757-07:00 NOTICE kernel: [45305.939738] Modules linked in: evdi uinput rfcomm i2c_dev hid_multitouch snd_usb_audio snd_usbmidi_lib uvcvideo videobuf2_vmalloc ip6t_REJECT zram cmac memc_x86 snd_hda_codec_realtek snd_hda_codec_generic snd_hda_codec_hdmi x86_pkg_temp_thermal snd_hda_intel snd_hda_controller snd_hda_codec snd_soc_sst_acpi snd_hwdep fuse ip6table_filter ip6_tables snd_seq_midi snd_seq_midi_event snd_rawmidi snd_seq snd_seq_device joydev btusb btbcm btintel bluetooth iwlmvm r8169 mii iwlwifi iwl7000_mac80211 cfg80211 2017-10-25T03:37:06.982761-07:00 NOTICE kernel: [45305.939913] CPU: 2 PID: 0 Comm: swapper/2 Not tainted 3.14.0 #1 2017-10-25T03:37:06.982764-07:00 NOTICE kernel: [45305.939927] Hardware name: GOOGLE Guado, BIOS Google_Guado.6301.108.4 04/01/2015 2017-10-25T03:37:06.982768-07:00 NOTICE kernel: [45305.939946] 0000000000000000 00168c2ffb3d4c5e ffff88017ed03d90 ffffffffb321b58c 2017-10-25T03:37:06.982771-07:00 NOTICE kernel: [45305.939974] ffff88017ed03dd8 ffff88017ed03dc8 ffffffffb2c5d7e8 ffffffffb2f628ac 2017-10-25T03:37:06.982774-07:00 NOTICE kernel: [45305.940000] ffff880178eb1800 ffff880178e60000 ffff880178eb1a08 0000000000000000 2017-10-25T03:37:06.982777-07:00 NOTICE kernel: [45305.940028] Call Trace: 2017-10-25T03:37:06.982780-07:00 NOTICE kernel: [45305.940038] <IRQ> [<ffffffffb321b58c>] dump_stack+0x4d/0x6f 2017-10-25T03:37:06.982783-07:00 NOTICE kernel: [45305.940074] [<ffffffffb2c5d7e8>] warn_slowpath_common+0x7f/0x98 2017-10-25T03:37:06.982786-07:00 NOTICE kernel: [45305.940097] [<ffffffffb2f628ac>] ? intel_check_page_flip+0x98/0xbd 2017-10-25T03:37:06.982789-07:00 NOTICE kernel: [45305.940121] [<ffffffffb2c5d858>] warn_slowpath_fmt+0x57/0x73 2017-10-25T03:37:06.982792-07:00 NOTICE kernel: [45305.940144] [<ffffffffb2f55376>] ? __intel_pageflip_stall_check+0xc9/0xf7 2017-10-25T03:37:06.982795-07:00 NOTICE kernel: [45305.940168] [<ffffffffb2f628ac>] intel_check_page_flip+0x98/0xbd 2017-10-25T03:37:06.982798-07:00 NOTICE kernel: [45305.940192] [<ffffffffb2f3b9af>] gen8_irq_handler+0x3e1/0x53c 2017-10-25T03:37:06.982800-07:00 NOTICE kernel: [45305.940216] [<ffffffffb2ca0cb7>] handle_irq_event_percpu+0x78/0x1aa 2017-10-25T03:37:06.982803-07:00 NOTICE kernel: [45305.940240] [<ffffffffb2ca0e27>] handle_irq_event+0x3e/0x5b 2017-10-25T03:37:06.982806-07:00 NOTICE kernel: [45305.940260] [<ffffffffb2ca369b>] handle_edge_irq+0xa4/0xbd 2017-10-25T03:37:06.982809-07:00 NOTICE kernel: [45305.940280] [<ffffffffb2c04427>] handle_irq+0x118/0x120 2017-10-25T03:37:06.982812-07:00 NOTICE kernel: [45305.940300] [<ffffffffb3225a4d>] do_IRQ+0x4d/0xc2 2017-10-25T03:37:06.982815-07:00 NOTICE kernel: [45305.940321] [<ffffffffb32232ea>] common_interrupt+0x6a/0x6a 2017-10-25T03:37:06.982819-07:00 NOTICE kernel: [45305.940337] <EOI> [<ffffffffb30ba099>] ? cpuidle_enter_state+0x44/0x99 2017-10-25T03:37:06.982822-07:00 NOTICE kernel: [45305.940368] [<ffffffffb30ba1d8>] cpuidle_idle_call+0xea/0x14b 2017-10-25T03:37:06.982824-07:00 NOTICE kernel: [45305.940388] [<ffffffffb2c0b406>] arch_cpu_idle+0xe/0x1d 2017-10-25T03:37:06.982827-07:00 NOTICE kernel: [45305.940409] [<ffffffffb2ca04c0>] cpu_startup_entry+0x16d/0x1cb 2017-10-25T03:37:06.982830-07:00 NOTICE kernel: [45305.940432] [<ffffffffb2c294c1>] start_secondary+0x1fa/0x213 2017-10-25T03:37:06.982833-07:00 WARNING kernel: [45305.940451] ---[ end trace b841007259963371 ]--- 2017-10-25T03:37:07.003987-07:00 INFO crash_reporter[22281]: Processing kernel warning: always collect from developer builds 2017-10-25T03:37:07.004785-07:00 INFO crash_reporter[22281]: Stored kernel warning into /var/spool/crash/kernel_warning.20171025.033707.0.kcrash 05:37:20 INFO | autoserv| Checking periphreal devices during meeting 05:37:21 INFO | autoserv| Hotrod volume is 32 for Bluestreak 100.107.146.14 05:37:21 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 32, cras: 32 05:37:21 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:37:23 INFO | autoserv| check device 2bd9:0011 05:37:23 INFO | autoserv| check device 18d1:8001 05:37:23 INFO | autoserv| check device 17e9:016b 05:37:23 INFO | autoserv| check device 266e:0110 05:37:23 INFO | autoserv| Start to mute camera for Bluestreak 100.107.146.14 05:37:28 INFO | autoserv| Start to mute microphone for Bluestreak 100.107.146.14 05:37:33 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:37:38 INFO | autoserv| Checking periphreal devices during meeting 05:37:40 INFO | autoserv| Hotrod volume is 6 for Bluestreak 100.107.146.14 05:37:40 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 6, cras: 6 05:38:16 INFO | autoserv| Checking periphreal devices during meeting 05:38:17 INFO | autoserv| Hotrod volume is 6 for Bluestreak 100.107.146.14 05:38:17 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 6, cras: 6 05:38:17 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:38:19 INFO | autoserv| check device 2bd9:0011 05:38:19 INFO | autoserv| check device 18d1:8001 05:38:19 INFO | autoserv| check device 17e9:016b 05:38:19 INFO | autoserv| check device 266e:0110 05:38:19 INFO | autoserv| Start to unmute camera for Bluestreak 100.107.146.14 05:38:24 INFO | autoserv| Start to unmute microphone for Bluestreak 100.107.146.14 05:38:29 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:38:34 INFO | autoserv| Checking periphreal devices during meeting 05:38:36 INFO | autoserv| Hotrod volume is 5 for Bluestreak 100.107.146.14 05:38:36 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 5, cras: 5 05:39:13 INFO | autoserv| Checking periphreal devices during meeting 05:39:14 INFO | autoserv| Hotrod volume is 5 for Bluestreak 100.107.146.14 05:39:14 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 5, cras: 5 05:39:14 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:39:16 INFO | autoserv| check device 2bd9:0011 05:39:16 INFO | autoserv| check device 18d1:8001 05:39:16 INFO | autoserv| check device 17e9:016b 05:39:16 INFO | autoserv| check device 266e:0110 05:39:16 INFO | autoserv| Start to mute camera for Bluestreak 100.107.146.14 05:39:21 INFO | autoserv| Start to mute microphone for Bluestreak 100.107.146.14 05:39:26 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:39:31 INFO | autoserv| Checking periphreal devices during meeting 05:39:33 INFO | autoserv| Hotrod volume is 97 for Bluestreak 100.107.146.14 05:39:33 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 97, cras: 97 05:40:05 INFO | autoserv| Checking periphreal devices during meeting 05:40:06 INFO | autoserv| Hotrod volume is 97 for Bluestreak 100.107.146.14 05:40:06 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 97, cras: 97 05:40:06 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:40:08 INFO | autoserv| check device 2bd9:0011 05:40:08 INFO | autoserv| check device 18d1:8001 05:40:08 INFO | autoserv| check device 17e9:016b 05:40:08 INFO | autoserv| check device 266e:0110 05:40:08 INFO | autoserv| Start to unmute camera for Bluestreak 100.107.146.14 05:40:13 INFO | autoserv| Start to unmute microphone for Bluestreak 100.107.146.14 05:40:18 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:40:23 INFO | autoserv| Checking periphreal devices during meeting 05:40:25 INFO | autoserv| Hotrod volume is 71 for Bluestreak 100.107.146.14 05:40:25 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 71, cras: 71 05:40:59 INFO | autoserv| Checking periphreal devices during meeting 05:41:00 INFO | autoserv| Hotrod volume is 71 for Bluestreak 100.107.146.14 05:41:00 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 71, cras: 71 05:41:00 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:41:02 INFO | autoserv| check device 2bd9:0011 05:41:02 INFO | autoserv| check device 18d1:8001 05:41:02 INFO | autoserv| check device 17e9:016b 05:41:02 INFO | autoserv| check device 266e:0110 05:41:02 INFO | autoserv| Start to mute camera for Bluestreak 100.107.146.14 05:41:07 INFO | autoserv| Start to mute microphone for Bluestreak 100.107.146.14 05:41:12 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:41:17 INFO | autoserv| Checking periphreal devices during meeting 05:41:19 INFO | autoserv| Hotrod volume is 40 for Bluestreak 100.107.146.14 05:41:19 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 40, cras: 40 05:42:00 INFO | autoserv| Checking periphreal devices during meeting 05:42:01 INFO | autoserv| Hotrod volume is 40 for Bluestreak 100.107.146.14 05:42:01 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 40, cras: 40 05:42:01 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:42:03 INFO | autoserv| check device 2bd9:0011 05:42:03 INFO | autoserv| check device 18d1:8001 05:42:03 INFO | autoserv| check device 17e9:016b 05:42:03 INFO | autoserv| check device 266e:0110 05:42:03 INFO | autoserv| Start to unmute camera for Bluestreak 100.107.146.14 05:42:08 INFO | autoserv| Start to unmute microphone for Bluestreak 100.107.146.14 05:42:13 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:42:19 INFO | autoserv| Checking periphreal devices during meeting 05:42:20 INFO | autoserv| Hotrod volume is 49 for Bluestreak 100.107.146.14 05:42:20 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 49, cras: 49 05:42:53 INFO | autoserv| Checking periphreal devices during meeting 05:42:54 INFO | autoserv| Hotrod volume is 49 for Bluestreak 100.107.146.14 05:42:55 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 49, cras: 49 05:42:55 INFO | autoserv| Checking usb devices in usb kernel: ['2bd9:0011', '18d1:8001', '17e9:016b', '266e:0110'] 05:42:56 INFO | autoserv| check device 2bd9:0011 05:42:56 INFO | autoserv| check device 18d1:8001 05:42:56 INFO | autoserv| check device 17e9:016b 05:42:56 INFO | autoserv| check device 266e:0110 05:42:56 INFO | autoserv| Start to mute camera for Bluestreak 100.107.146.14 05:43:01 INFO | autoserv| Start to mute microphone for Bluestreak 100.107.146.14 05:43:06 INFO | autoserv| Start to change volume for Bluestreak 100.107.146.14 05:43:11 INFO | autoserv| Checking periphreal devices during meeting 05:43:13 INFO | autoserv| Hotrod volume is 39 for Bluestreak 100.107.146.14 05:43:13 INFO | autoserv| Volume in BlueStreak and cras are sync for node 9? cfm: 39, cras: 39 05:43:53 INFO | autoserv| Detected kernel error: error = [ cut here ] 05:43:53 INFO | autoserv| Detected kernel error: error = Call Trace: 05:43:53 INFO | autoserv| Detected kernel error: error = end trace 05:43:53 INFO | autoserv| Found error when scanning log files for 100.107.146.14 for guado ip [ cut here ] 05:43:53 INFO | autoserv| Found error when scanning log files for 100.107.146.14 for guado ip Call Trace: 05:43:53 INFO | autoserv| Found error when scanning log files for 100.107.146.14 for guado ip end trace 05:43:53 INFO | autoserv| Found error in log file for guado ip 100.107.146.14 05:43:53 INFO | autoserv| Failure detected when checking log on Guado 100.107.146.14, 05:43:53 INFO | autoserv| Test is aborted due to failure detected in log files. 05:44:04 INFO | autoserv| Executing /tmp/sysinfo/autoserv-qlWBWJ/bin/autotest /tmp/sysinfo/autoserv-qlWBWJ/control phase 0 05:44:04 INFO | autoserv| Entered autotestd_monitor. 05:44:04 INFO | autoserv| Finished launching tail subprocesses. 05:44:04 INFO | autoserv| Finished waiting on autotestd to start. 05:44:04 INFO | autoserv| START ---- ---- timestamp=1508928243 localtime=Oct 25 05:44:03 05:44:04 INFO | autoserv| GOOD ---- sysinfo.iteration.after timestamp=1508928243 localtime=Oct 25 05:44:03 05:44:05 INFO | autoserv| END GOOD ---- ---- timestamp=1508928243 localtime=Oct 25 05:44:03 05:44:05 INFO | autoserv| Got lock of exit_code_file. 05:44:05 INFO | autoserv| Released lock of exit_code_file and closed it. 05:44:07 INFO | autoserv| Killing child processes. 05:44:07 INFO | autoserv| Client complete 05:44:07 INFO | autoserv| The test failed with the following exception 05:44:07 INFO | autoserv| Traceback (most recent call last): 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 606, in _exec 05:44:07 INFO | autoserv| _call_test_function(self.execute, *p_args, **p_dargs) 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 806, in _call_test_function 05:44:07 INFO | autoserv| return func(*args, **dargs) 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 470, in execute 05:44:07 INFO | autoserv| dargs) 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 347, in _call_run_once_with_retry 05:44:07 INFO | autoserv| postprocess_profiled_run, args, dargs) 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/client/common_lib/test.py", line 380, in _call_run_once 05:44:07 INFO | autoserv| self.run_once(*args, **dargs) 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/enterprise_CFM_BlueStreak/enterprise_CFM_BlueStreak.py", line 1245, in run_once 05:44:07 INFO | autoserv| self.error_list, True) 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/enterprise_CFM_BlueStreak/enterprise_CFM_BlueStreak.py", line 219, in _check_logfile_error 05:44:07 INFO | autoserv| if not self._search_for_error_events(error_list, no_usb_flap): 05:44:07 INFO | autoserv| File "/mnt/host/source/src/third_party/autotest/files/server/site_tests/enterprise_CFM_BlueStreak/enterprise_CFM_BlueStreak.py", line 209, in _search_for_error_events 05:44:07 INFO | autoserv| raise error.TestFail('Fail detected when ' 05:44:07 INFO | autoserv| TestFail: Fail detected when checking log files on Guado 05:44:08 INFO | autoserv| Executing /tmp/sysinfo/autoserv-qlWBWJ/bin/autotest /tmp/sysinfo/autoserv-qlWBWJ/control phase 0 05:44:08 INFO | autoserv| Entered autotestd_monitor. 05:44:08 INFO | autoserv| Finished launching tail subprocesses. 05:44:08 INFO | autoserv| Finished waiting on autotestd to start. 05:44:09 INFO | autoserv| START ---- ---- timestamp=1508928247 localtime=Oct 25 05:44:07 05:44:10 INFO | autoserv| GOOD ---- sysinfo.after timestamp=1508928249 localtime=Oct 25 05:44:09 05:44:10 INFO | autoserv| END GOOD ---- ---- timestamp=1508928249 localtime=Oct 25 05:44:09 05:44:10 INFO | autoserv| Got lock of exit_code_file. 05:44:10 INFO | autoserv| Released lock of exit_code_file and closed it. 05:44:12 INFO | autoserv| Killing child processes. 05:44:12 INFO | autoserv| Client complete 05:44:19 INFO | autoserv| FAIL enterprise_CFM_BlueStreak.all enterprise_CFM_BlueStreak.all timestamp=1508928259 localtime=Oct 25 05:44:19 Fail detected when checking log files on Guado 05:44:19 INFO | autoserv| END FAIL enterprise_CFM_BlueStreak.all enterprise_CFM_BlueStreak.all timestamp=1508928259 localtime=Oct 25 05:44:19 05:44:19 INFO | autoserv| Waiting for RPC server "multimedia_xmlrpc_server" shutdown 05:44:19 INFO | autoserv| Shut down RPC server. 05:44:19 INFO | autoserv| Finished processing control file 05:44:19 INFO | autoserv| tko parser: {'drone': 'minzhuo2.svl.corp.google.com', 'user': 'autotest_system', 'job_started': 1508893507, 'hostname': '100.107.146.14', 'status_version': 1, 'experimental': 'False', 'label': 'ad_hoc_build/ad_hoc_suite/enterprise_CFM_BlueStreak.all'} 05:44:19 INFO | autoserv| tko parser: MACHINE NAME: 100.107.146.14 05:44:19 INFO | autoserv| tko parser: Unable to parse host keyval for 100.107.146.14 05:44:19 INFO | autoserv| tko parser: MACHINE GROUP: 05:44:19 INFO | autoserv| tko parser: parsing partial test ---- SERVER_JOB 05:44:19 INFO | autoserv| tko parser: parsing partial test enterprise_CFM_BlueStreak.all enterprise_CFM_BlueStreak.all 05:44:19 INFO | autoserv| tko parser: RUNNING: RUNNING 05:44:19 INFO | autoserv| Subdir: enterprise_CFM_BlueStreak.all 05:44:19 INFO | autoserv| Testname: enterprise_CFM_BlueStreak.all 05:44:19 INFO | autoserv| 05:44:19 INFO | autoserv| tko parser: update RUNNING reason: Fail detected when checking log files on Guado 05:44:19 INFO | autoserv| tko parser: parsing test enterprise_CFM_BlueStreak.all enterprise_CFM_BlueStreak.all 05:44:19 INFO | autoserv| tko parser: ADD: FAIL 05:44:19 INFO | autoserv| Subdir: enterprise_CFM_BlueStreak.all 05:44:19 INFO | autoserv| Testname: enterprise_CFM_BlueStreak.all 05:44:19 INFO | autoserv| Fail detected when checking log files on Guado 05:44:19 INFO | autoserv| tko parser: parsing test ---- SERVER_JOB 05:44:22 INFO | autoserv| There are no orphaned crashes; deleting /tmp/test_that_results_jEt7eh/results-1-enterprise_CFM_BlueStreak.all/crashinfo.100.107.146.14 05:44:22 INFO | autoserv| get_network_stats: at-end RXbytes 16612282828 TXbytes 8543343280 05:44:23 INFO | autoserv| record_state_duration failed: job_or_task_id=None, hostname=100.107.146.14, status=Running --------------------------------------------------------------------------------------------------------------- /tmp/test_that_results_jEt7eh/results-1-enterprise_CFM_BlueStreak.all [ FAILED ] /tmp/test_that_results_jEt7eh/results-1-enterprise_CFM_BlueStreak.all FAIL: Fail detected when checking log files on Guado /tmp/test_that_results_jEt7eh/results-1-enterprise_CFM_BlueStreak.all/enterprise_CFM_BlueStreak.all [ FAILED ] /tmp/test_that_results_jEt7eh/results-1-enterprise_CFM_BlueStreak.all/enterprise_CFM_BlueStreak.all FAIL: Fail detected when checking log files on Guado --------------------------------------------------------------------------------------------------------------- Total PASS: 0/2 (0%) 05:44:24 INFO | Finished running tests. Results can be found in /tmp/test_that_results_jEt7eh or /tmp/test_that_latest (cr) ((237117b1f...)) mzhuo@minzhuo2 ~/trunk/src/scripts $ (cr) ((237117b1f...)) mzhuo@minzhuo2 ~/trunk/src/scripts $
,
Oct 25 2017
,
Oct 25 2017
,
Feb 16 2018
Hit the kernel crash on the device, running chrome OS:R63-10032.86.0, DUT: Guado 2018-02-14T17:02:06.136767-06:00 INFO crash_reporter[3512]: Enabling kernel crash handling 2018-02-14T17:02:06.152442-06:00 INFO crash_reporter[3512]: Received prior crash notification from kernel (signature kernel-(HANG)-hung_task: blocked tasks-1B01766D) (developer build - always dumping) 2018-02-14T17:02:06.153035-06:00 INFO crash_reporter[3512]: Stored kcrash to /var/spool/crash/kernel.20180214.170206.0.kcrash 2018-02-14T17:02:06.153084-06:00 WARNING crash_reporter[3512]: Last shutdown was not clean 2018-02-14T17:03:04.927138-06:00 ERR chrome[909]: [909:909:0214/170304.927068:INFO:status_uploader.cc(222)] Starting status upload: have_device_status = 1 2018-02-14T17:03:05.215375-06:00 ERR chrome[909]: [909:909:0214/170305.215295:INFO:status_uploader.cc(235)] Status upload successful 2018-02-14T17:03:44.116384-06:00 INFO sshd[3742]: Accepted publickey for root from 100.123.162.40 port 49188 ssh2: RSA SHA256:Fp1qWjFLyK1cTpiI5rdk7iEJwoK9lcnYAgbQtGC3jzU 2018-02-14T17:04:23.099464-06:00 ERR kernel: [ 155.485544] go2001 0000:03:00.0: go2001_dec_g_fmt_cap:1579 Frame info not available yet 2018-02-14T17:04:23.107413-06:00 DEBUG kernel: [ 155.493065] delay: estimated 192, actual 48 2018-02-14T17:04:23.267441-06:00 ERR kernel: [ 155.653348] go2001 0000:03:00.0: go2001_dec_g_fmt_cap:1579 Frame info not available yet 2018-02-14T17:04:23.281586-06:00 ERR kernel: [ 155.667973] go2001 0000:03:00.0: go2001_dec_g_fmt_cap:1579 Frame info not available yet 2018-02-14T17:04:23.300432-06:00 ERR kernel: [ 155.686220] go2001 0000:03:00.0: go2001_dec_g_fmt_cap:1579 Frame info not available yet 2018-02-14T17:04:23.423435-06:00 ERR kernel: [ 155.809864] go2001 0000:03:00.0: go2001_dec_g_fmt_cap:1579 Frame info not available yet 2018-02-14T17:04:23.438429-06:00 DEBUG kernel: [ 155.824335] delay: estimated 240, actual 0 2018-02-14T17:04:24.079441-06:00 ERR kernel: [ 156.466754] go2001 0000:03:00.0: go2001_dec_g_fmt_cap:1579 Frame info not available yet
,
Feb 16 2018
,
Feb 16 2018
,
Feb 16 2018
,
Aug 3
This bug has an owner, thus, it's been triaged. Changing status to "assigned".
,
Aug 25
This is old and hasn't been started yet. Moving back to available. |
||||||||||||||||||||||
►
Sign in to add a comment |
||||||||||||||||||||||
Comment 1 by mzhuo@chromium.org
, Aug 6 20172.2 MB
2.2 MB Download
3.6 KB
3.6 KB Download
175 bytes
175 bytes Download