Warning: Permanently added '10.128.15.203' (ECDSA) to the list of known hosts. 2019/08/19 15:35:15 parsed 1 programs 2019/08/19 15:35:15 executed programs: 0 [ 39.128338][ T1856] cgroup1: Unknown subsys name 'perf_event' [ 39.128585][ T1859] cgroup1: Unknown subsys name 'perf_event' [ 39.134918][ T1856] cgroup1: Unknown subsys name 'net_cls' [ 39.142231][ T1859] cgroup1: Unknown subsys name 'net_cls' [ 39.151657][ T1861] cgroup1: Unknown subsys name 'perf_event' [ 39.159341][ T1861] cgroup1: Unknown subsys name 'net_cls' [ 39.160299][ T1863] cgroup1: Unknown subsys name 'perf_event' [ 39.177258][ T1863] cgroup1: Unknown subsys name 'net_cls' [ 39.187009][ T1866] cgroup1: Unknown subsys name 'perf_event' [ 39.193423][ T1866] cgroup1: Unknown subsys name 'net_cls' [ 39.203117][ T1875] cgroup1: Unknown subsys name 'perf_event' [ 39.210614][ T1875] cgroup1: Unknown subsys name 'net_cls' [ 42.435668][ T101] usb 4-1: new low-speed USB device number 2 using dummy_hcd [ 42.565523][ T12] usb 1-1: new low-speed USB device number 2 using dummy_hcd [ 42.585505][ T21] usb 3-1: new low-speed USB device number 2 using dummy_hcd [ 42.585548][ T5] usb 5-1: new low-speed USB device number 2 using dummy_hcd [ 42.605527][ T1742] usb 6-1: new low-speed USB device number 2 using dummy_hcd [ 42.635492][ T2856] usb 2-1: new low-speed USB device number 2 using dummy_hcd [ 42.795551][ T101] usb 4-1: config 0 has an invalid interface number: 236 but max is 2 [ 42.803881][ T101] usb 4-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 42.814214][ T101] usb 4-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 42.823109][ T101] usb 4-1: config 0 has no interface number 0 [ 42.829432][ T101] usb 4-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 42.839631][ T101] usb 4-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 42.849094][ T101] usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 42.859087][ T101] usb 4-1: config 0 descriptor?? [ 42.900825][ T101] iowarrior 4-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 [ 42.965600][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 2 [ 42.974075][ T12] usb 1-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 42.975525][ T21] usb 3-1: config 0 has an invalid interface number: 236 but max is 2 [ 42.984297][ T12] usb 1-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 42.984311][ T12] usb 1-1: config 0 has no interface number 0 [ 42.992552][ T21] usb 3-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 43.001556][ T5] usb 5-1: config 0 has an invalid interface number: 236 but max is 2 [ 43.007497][ T21] usb 3-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 43.017702][ T5] usb 5-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 43.025837][ T21] usb 3-1: config 0 has no interface number 0 [ 43.034659][ T5] usb 5-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 43.045836][ T21] usb 3-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 43.051026][ T5] usb 5-1: config 0 has no interface number 0 [ 43.051104][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 43.060056][ T21] usb 3-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 43.060069][ T21] usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 43.065525][ T1742] usb 6-1: config 0 has an invalid interface number: 236 but max is 2 [ 43.070249][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 43.076345][ T1742] usb 6-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 43.086494][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 43.095522][ T1742] usb 6-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 43.095532][ T1742] usb 6-1: config 0 has no interface number 0 [ 43.099782][ T21] usb 3-1: config 0 descriptor?? [ 43.103580][ T2856] usb 2-1: config 0 has an invalid interface number: 236 but max is 2 [ 43.103595][ T2856] usb 2-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 43.112074][ T1742] usb 6-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 43.120845][ T2856] usb 2-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 43.120857][ T2856] usb 2-1: config 0 has no interface number 0 [ 43.124017][ T5] usb 5-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 43.131212][ T1742] usb 6-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 43.139674][ T5] usb 5-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 43.139687][ T5] usb 5-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 43.139963][ T2856] usb 2-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 43.148651][ T1742] usb 6-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 43.154711][ T2856] usb 2-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 43.165858][ T21] iowarrior 3-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior1 [ 43.167946][ T2856] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 43.185322][ T1742] usb 6-1: config 0 descriptor?? [ 43.206005][ T12] usb 1-1: config 0 descriptor?? [ 43.215522][ T21] usb 4-1: USB disconnect, device number 2 [ 43.222958][ T5] usb 5-1: config 0 descriptor?? [ 43.237355][ T21] [ 43.242002][ T2856] usb 2-1: config 0 descriptor?? [ 43.249657][ T21] ====================================================== [ 43.249661][ T21] WARNING: possible circular locking dependency detected [ 43.249667][ T21] 5.3.0-rc4+ #1 Not tainted [ 43.249669][ T21] ------------------------------------------------------ [ 43.249674][ T21] kworker/1:1/21 is trying to acquire lock: [ 43.249679][ T21] 00000000bfac431a (minor_rwsem){++++}, at: usb_deregister_dev+0x61/0x270 [ 43.249705][ T21] [ 43.249705][ T21] but task is already holding lock: [ 43.361454][ T21] 000000007638fa06 (iowarrior_open_disc_lock){+.+.}, at: iowarrior_disconnect+0x45/0x2c0 [ 43.371254][ T21] [ 43.371254][ T21] which lock already depends on the new lock. [ 43.371254][ T21] [ 43.381638][ T21] [ 43.381638][ T21] the existing dependency chain (in reverse order) is: [ 43.388565][ T2851] iowarrior_open - error, can't find device for minor 0 [ 43.390627][ T21] [ 43.390627][ T21] -> #2 (iowarrior_open_disc_lock){+.+.}: [ 43.390650][ T21] __mutex_lock+0x158/0x1360 [ 43.390667][ T21] iowarrior_open+0x8a/0x2a0 [ 43.400383][ T2867] usb 3-1: USB disconnect, device number 2 [ 43.406159][ T21] usb_open+0x1df/0x270 [ 43.406170][ T21] chrdev_open+0x219/0x5c0 [ 43.406180][ T21] do_dentry_open+0x494/0x1120 [ 43.406188][ T21] path_openat+0x1430/0x3f50 [ 43.406202][ T21] do_filp_open+0x1a1/0x280 [ 43.448352][ T21] do_sys_open+0x3c0/0x580 [ 43.453934][ T21] do_syscall_64+0xb7/0x580 [ 43.458963][ T21] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 43.465350][ T21] [ 43.465350][ T21] -> #1 (iowarrior_mutex){+.+.}: [ 43.472472][ T21] __mutex_lock+0x158/0x1360 [ 43.477597][ T21] iowarrior_open+0x23/0x2a0 [ 43.482696][ T21] usb_open+0x1df/0x270 [ 43.487452][ T21] chrdev_open+0x219/0x5c0 [ 43.492479][ T21] do_dentry_open+0x494/0x1120 [ 43.497742][ T21] path_openat+0x1430/0x3f50 [ 43.502850][ T21] do_filp_open+0x1a1/0x280 [ 43.507858][ T21] do_sys_open+0x3c0/0x580 [ 43.512776][ T21] do_syscall_64+0xb7/0x580 [ 43.517785][ T21] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 43.524441][ T21] [ 43.524441][ T21] -> #0 (minor_rwsem){++++}: [ 43.531228][ T21] __lock_acquire+0x1f7c/0x3b50 [ 43.537189][ T21] lock_acquire+0x127/0x320 [ 43.543066][ T21] down_write+0x92/0x150 [ 43.547934][ T21] usb_deregister_dev+0x61/0x270 [ 43.553377][ T21] iowarrior_disconnect+0xa8/0x2c0 [ 43.560395][ T21] usb_unbind_interface+0x1bd/0x8a0 [ 43.566123][ T21] device_release_driver_internal+0x42f/0x500 [ 43.572697][ T21] bus_remove_device+0x2dc/0x4a0 [ 43.578133][ T21] device_del+0x420/0xb10 [ 43.583053][ T21] usb_disable_device+0x211/0x690 [ 43.588614][ T21] usb_disconnect+0x284/0x8d0 [ 43.593961][ T21] hub_event+0x1454/0x3640 [ 43.598967][ T21] process_one_work+0x92b/0x1530 [ 43.604423][ T21] worker_thread+0x96/0xe20 [ 43.609475][ T21] kthread+0x318/0x420 [ 43.614071][ T21] ret_from_fork+0x24/0x30 [ 43.618985][ T21] [ 43.618985][ T21] other info that might help us debug this: [ 43.618985][ T21] [ 43.629192][ T21] Chain exists of: [ 43.629192][ T21] minor_rwsem --> iowarrior_mutex --> iowarrior_open_disc_lock [ 43.629192][ T21] [ 43.642895][ T21] Possible unsafe locking scenario: [ 43.642895][ T21] [ 43.650417][ T21] CPU0 CPU1 [ 43.655762][ T21] ---- ---- [ 43.661192][ T21] lock(iowarrior_open_disc_lock); [ 43.666366][ T21] lock(iowarrior_mutex); [ 43.673276][ T21] lock(iowarrior_open_disc_lock); [ 43.681054][ T21] lock(minor_rwsem); [ 43.685098][ T21] [ 43.685098][ T21] *** DEADLOCK *** [ 43.685098][ T21] [ 43.693405][ T21] 6 locks held by kworker/1:1/21: [ 43.699408][ T21] #0: 00000000ffafc5b3 ((wq_completion)usb_hub_wq){+.+.}, at: process_one_work+0x827/0x1530 [ 43.709548][ T21] #1: 000000005bc0df0d ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x85b/0x1530 [ 43.720202][ T21] #2: 00000000f73a9504 (&dev->mutex){....}, at: hub_event+0x17c/0x3640 [ 43.728511][ T21] #3: 000000006fe9ca35 (&dev->mutex){....}, at: usb_disconnect+0x91/0x8d0 [ 43.737076][ T21] #4: 0000000044c331cb (&dev->mutex){....}, at: device_release_driver_internal+0x23/0x500 [ 43.747403][ T21] #5: 000000007638fa06 (iowarrior_open_disc_lock){+.+.}, at: iowarrior_disconnect+0x45/0x2c0 [ 43.757975][ T21] [ 43.757975][ T21] stack backtrace: [ 43.763855][ T21] CPU: 1 PID: 21 Comm: kworker/1:1 Not tainted 5.3.0-rc4+ #1 [ 43.771657][ T21] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 43.781813][ T21] Workqueue: usb_hub_wq hub_event [ 43.786813][ T21] Call Trace: [ 43.790085][ T21] dump_stack+0xca/0x13e [ 43.794317][ T21] check_noncircular+0x345/0x3e0 [ 43.799343][ T21] ? print_circular_bug+0x1b0/0x1b0 [ 43.804545][ T21] ? __lock_acquire+0x2605/0x3b50 [ 43.809563][ T21] ? graph_lock+0x80/0x180 [ 43.815586][ T21] ? hlock_class+0x120/0x120 [ 43.820156][ T21] ? mark_lock+0xbc/0x1130 [ 43.824555][ T21] ? find_first_zero_bit+0x94/0xb0 [ 43.829661][ T21] __lock_acquire+0x1f7c/0x3b50 [ 43.834525][ T21] ? mark_held_locks+0xe0/0xe0 [ 43.839299][ T21] ? __mutex_lock+0x430/0x1360 [ 43.844068][ T21] ? usb_kill_urb+0x198/0x2c0 [ 43.848735][ T21] lock_acquire+0x127/0x320 [ 43.853230][ T21] ? usb_deregister_dev+0x61/0x270 [ 43.858338][ T21] down_write+0x92/0x150 [ 43.862654][ T21] ? usb_deregister_dev+0x61/0x270 [ 43.867766][ T21] ? __down_timeout+0x2d0/0x2d0 [ 43.872696][ T21] usb_deregister_dev+0x61/0x270 [ 43.877618][ T21] iowarrior_disconnect+0xa8/0x2c0 [ 43.882705][ T21] usb_unbind_interface+0x1bd/0x8a0 [ 43.887905][ T21] ? usb_autoresume_device+0x60/0x60 [ 43.893221][ T21] device_release_driver_internal+0x42f/0x500 [ 43.899427][ T21] bus_remove_device+0x2dc/0x4a0 [ 43.904366][ T21] device_del+0x420/0xb10 [ 43.908704][ T21] ? __device_links_no_driver+0x240/0x240 [ 43.914405][ T21] ? usb_remove_ep_devs+0x3e/0x80 [ 43.919411][ T21] ? remove_intf_ep_devs+0x13f/0x1d0 [ 43.924679][ T21] usb_disable_device+0x211/0x690 [ 43.929793][ T21] usb_disconnect+0x284/0x8d0 [ 43.934540][ T21] hub_event+0x1454/0x3640 [ 43.939050][ T21] ? find_held_lock+0x2d/0x110 [ 43.943821][ T21] ? mark_held_locks+0xe0/0xe0 [ 43.948580][ T21] ? hub_port_debounce+0x260/0x260 [ 43.953676][ T21] process_one_work+0x92b/0x1530 [ 43.958611][ T21] ? pwq_dec_nr_in_flight+0x310/0x310 [ 43.964545][ T21] ? do_raw_spin_lock+0x11a/0x280 [ 43.969590][ T21] worker_thread+0x96/0xe20 [ 43.974089][ T21] ? process_one_work+0x1530/0x1530 [ 43.979267][ T21] kthread+0x318/0x420 [ 43.983314][ T21] ? kthread_create_on_node+0xf0/0xf0 [ 43.988665][ T21] ret_from_fork+0x24/0x30 [ 43.994853][ T1742] sysfs: cannot create duplicate filename '/class/usbmisc/iowarrior0' [ 44.003071][ T1742] CPU: 1 PID: 1742 Comm: kworker/1:3 Not tainted 5.3.0-rc4+ #1 [ 44.010612][ T1742] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 44.020654][ T1742] Workqueue: usb_hub_wq hub_event [ 44.025656][ T1742] Call Trace: [ 44.029055][ T1742] dump_stack+0xca/0x13e [ 44.033296][ T1742] sysfs_warn_dup.cold+0x1c/0x29 [ 44.038242][ T1742] sysfs_do_create_link_sd.isra.0+0x10f/0x130 [ 44.044292][ T1742] sysfs_create_link+0x61/0xc0 [ 44.050226][ T1742] device_add+0x788/0x16f0 [ 44.056906][ T1742] ? uevent_store+0x50/0x50 [ 44.061406][ T1742] ? kfree+0x230/0x2f0 [ 44.065488][ T1742] device_create_groups_vargs+0x203/0x280 [ 44.071196][ T1742] device_create+0xdf/0x120 [ 44.075700][ T1742] ? device_create_vargs+0x50/0x50 [ 44.080794][ T1742] ? down_write+0xe0/0x150 [ 44.085194][ T1742] ? __down_timeout+0x2d0/0x2d0 [ 44.090029][ T1742] usb_register_dev+0x279/0x6a0 [ 44.095032][ T1742] ? usb_open+0x270/0x270 [ 44.099365][ T1742] ? kasan_unpoison_shadow+0x30/0x40 [ 44.104635][ T1742] ? __kasan_kmalloc.constprop.0+0xbf/0xd0 [ 44.110420][ T1742] ? kasan_unpoison_shadow+0x30/0x40 [ 44.115701][ T1742] ? usb_string+0x3ab/0x480 [ 44.120193][ T1742] iowarrior_probe+0xaa4/0x10b2 [ 44.125060][ T1742] ? __pm_runtime_resume+0x111/0x180 [ 44.130346][ T1742] usb_probe_interface+0x305/0x7a0 [ 44.135440][ T1742] ? usb_probe_device+0x100/0x100 [ 44.140473][ T1742] really_probe+0x281/0x6d0 [ 44.144964][ T1742] driver_probe_device+0x101/0x1b0 [ 44.150057][ T1742] __device_attach_driver+0x1c2/0x220 [ 44.155673][ T1742] ? driver_allows_async_probing+0x160/0x160 [ 44.161633][ T1742] bus_for_each_drv+0x162/0x1e0 [ 44.166476][ T1742] ? bus_rescan_devices+0x20/0x20 [ 44.171484][ T1742] __device_attach+0x217/0x360 [ 44.176230][ T1742] ? device_bind_driver+0xd0/0xd0 [ 44.181238][ T1742] ? kobject_uevent_env+0x29e/0x1160 [ 44.186586][ T1742] ? kobject_uevent_env+0x2a8/0x1160 [ 44.191861][ T1742] bus_probe_device+0x1e4/0x290 [ 44.196715][ T1742] ? blocking_notifier_call_chain+0x54/0xa0 [ 44.202619][ T1742] device_add+0xae6/0x16f0 [ 44.207021][ T1742] ? uevent_store+0x50/0x50 [ 44.211526][ T1742] ? trace_hardirqs_on+0x55/0x1e0 [ 44.216554][ T1742] usb_set_configuration+0xdf6/0x1670 [ 44.221932][ T1742] generic_probe+0x9d/0xd5 [ 44.226355][ T1742] usb_probe_device+0x99/0x100 [ 44.231112][ T1742] ? usb_suspend+0x620/0x620 [ 44.235700][ T1742] really_probe+0x281/0x6d0 [ 44.240273][ T1742] driver_probe_device+0x101/0x1b0 [ 44.245385][ T1742] __device_attach_driver+0x1c2/0x220 [ 44.250740][ T1742] ? driver_allows_async_probing+0x160/0x160 [ 44.256698][ T1742] bus_for_each_drv+0x162/0x1e0 [ 44.261536][ T1742] ? bus_rescan_devices+0x20/0x20 [ 44.266979][ T1742] ? _raw_spin_unlock_irqrestore+0x3e/0x50 [ 44.272793][ T1742] ? lockdep_hardirqs_on+0x379/0x580 [ 44.278058][ T1742] __device_attach+0x217/0x360 [ 44.282802][ T1742] ? device_bind_driver+0xd0/0xd0 [ 44.287823][ T1742] ? kobject_uevent_env+0x29e/0x1160 [ 44.293229][ T1742] ? kobject_uevent_env+0x2a8/0x1160 [ 44.298531][ T1742] bus_probe_device+0x1e4/0x290 [ 44.303373][ T1742] ? blocking_notifier_call_chain+0x54/0xa0 [ 44.309246][ T1742] device_add+0xae6/0x16f0 [ 44.313642][ T1742] ? uevent_store+0x50/0x50 [ 44.318159][ T1742] usb_new_device.cold+0x6a4/0xe79 [ 44.323251][ T1742] hub_event+0x1b5c/0x3640 [ 44.327664][ T1742] ? hub_port_debounce+0x260/0x260 [ 44.332765][ T1742] process_one_work+0x92b/0x1530 [ 44.337684][ T1742] ? pwq_dec_nr_in_flight+0x310/0x310 [ 44.343063][ T1742] ? do_raw_spin_lock+0x11a/0x280 [ 44.348255][ T1742] worker_thread+0x96/0xe20 [ 44.352761][ T1742] ? process_one_work+0x1530/0x1530 [ 44.357962][ T1742] kthread+0x318/0x420 [ 44.362017][ T1742] ? kthread_create_on_node+0xf0/0xf0 [ 44.367373][ T1742] ret_from_fork+0x24/0x30 [ 44.373427][ T21] iowarrior 4-1:0.236: I/O-Warror #0 now disconnected [ 44.875413][ T21] usb 4-1: new low-speed USB device number 3 using dummy_hcd 2019/08/19 15:35:21 executed programs: 6 [ 45.195659][ T21] usb 4-1: device descriptor read/all, error -71 [ 45.565573][ T21] usb 4-1: new low-speed USB device number 4 using dummy_hcd [ 45.925495][ T21] usb 4-1: config 0 has an invalid interface number: 236 but max is 2 [ 45.933728][ T21] usb 4-1: config 0 has an invalid descriptor of length 99, skipping remainder of the config [ 45.943896][ T21] usb 4-1: config 0 has 1 interface, different from the descriptor's value: 3 [ 45.952762][ T21] usb 4-1: config 0 has no interface number 0 [ 45.958899][ T21] usb 4-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 45.969013][ T21] usb 4-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 45.978204][ T21] usb 4-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 45.986907][ T21] usb 4-1: config 0 descriptor?? 2019/08/19 15:35:28 executed programs: 14