INIT: Entering runlevel: 2 [info] Using makefile-style concurrent boot in runlevel 2. [....] Starting enhanced syslogd: rsyslogd[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting periodic command scheduler: cron[?25l[?1c7[ ok 8[?25h[?0c. [....] Starting OpenBSD Secure Shell server: sshd[?25l[?1c7[ ok 8[?25h[?0c. Debian GNU/Linux 7 syzkaller ttyS0 Warning: Permanently added '10.128.0.149' (ECDSA) to the list of known hosts. executing program syzkaller login: [ 24.035216][ T12] usb 1-1: new low-speed USB device number 2 using dummy_hcd [ 24.395228][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 24.403485][ T12] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 24.413774][ T12] usb 1-1: config 0 has no interface number 0 [ 24.419884][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 24.430004][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 24.439069][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 24.448471][ T12] usb 1-1: config 0 descriptor?? [ 24.490084][ T12] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 24.688376][ T12] usb 1-1: USB disconnect, device number 2 [ 24.689393][ T1718] iowarrior 1-1:0.236: Error -19 while submitting URB [ 24.696577][ T12] [ 24.703363][ T12] ====================================================== [ 24.710369][ T12] WARNING: possible circular locking dependency detected [ 24.717362][ T12] 5.3.0-rc2+ #25 Not tainted [ 24.721919][ T12] ------------------------------------------------------ [ 24.728913][ T12] kworker/0:1/12 is trying to acquire lock: [ 24.734775][ T12] 00000000cd63e8f1 (minor_rwsem){++++}, at: usb_deregister_dev+0x61/0x270 [ 24.743257][ T12] [ 24.743257][ T12] but task is already holding lock: [ 24.750612][ T12] 000000001d1989ef (iowarrior_open_disc_lock){+.+.}, at: iowarrior_disconnect+0x45/0x2c0 [ 24.760404][ T12] [ 24.760404][ T12] which lock already depends on the new lock. [ 24.760404][ T12] [ 24.770792][ T12] [ 24.770792][ T12] the existing dependency chain (in reverse order) is: [ 24.779786][ T12] [ 24.779786][ T12] -> #2 (iowarrior_open_disc_lock){+.+.}: [ 24.787675][ T12] __mutex_lock+0x158/0x1360 [ 24.792763][ T12] iowarrior_open+0x8a/0x2a0 [ 24.797859][ T12] usb_open+0x1df/0x270 [ 24.802526][ T12] chrdev_open+0x219/0x5c0 [ 24.807459][ T12] do_dentry_open+0x494/0x1120 [ 24.812728][ T12] path_openat+0x1430/0x3f50 [ 24.817834][ T12] do_filp_open+0x1a1/0x280 [ 24.822841][ T12] do_sys_open+0x3c0/0x580 [ 24.827758][ T12] do_syscall_64+0xb7/0x580 [ 24.832791][ T12] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 24.839178][ T12] [ 24.839178][ T12] -> #1 (iowarrior_mutex){+.+.}: [ 24.846307][ T12] __mutex_lock+0x158/0x1360 [ 24.851428][ T12] iowarrior_open+0x23/0x2a0 [ 24.856548][ T12] usb_open+0x1df/0x270 [ 24.861209][ T12] chrdev_open+0x219/0x5c0 [ 24.866130][ T12] do_dentry_open+0x494/0x1120 [ 24.871426][ T12] path_openat+0x1430/0x3f50 [ 24.876521][ T12] do_filp_open+0x1a1/0x280 [ 24.881547][ T12] do_sys_open+0x3c0/0x580 [ 24.886468][ T12] do_syscall_64+0xb7/0x580 [ 24.891681][ T12] entry_SYSCALL_64_after_hwframe+0x49/0xbe [ 24.898083][ T12] [ 24.898083][ T12] -> #0 (minor_rwsem){++++}: [ 24.905187][ T12] __lock_acquire+0x1f7c/0x3b50 [ 24.910570][ T12] lock_acquire+0x127/0x320 [ 24.915611][ T12] down_write+0x92/0x150 [ 24.920380][ T12] usb_deregister_dev+0x61/0x270 [ 24.925846][ T12] iowarrior_disconnect+0xa8/0x2c0 [ 24.931467][ T12] usb_unbind_interface+0x1bd/0x8a0 [ 24.937170][ T12] device_release_driver_internal+0x404/0x4c0 [ 24.943735][ T12] bus_remove_device+0x2dc/0x4a0 [ 24.949167][ T12] device_del+0x420/0xb10 [ 24.954019][ T12] usb_disable_device+0x211/0x690 [ 24.959547][ T12] usb_disconnect+0x284/0x8d0 [ 24.964725][ T12] hub_event+0x1454/0x3640 [ 24.969645][ T12] process_one_work+0x92b/0x1530 [ 24.975092][ T12] worker_thread+0x96/0xe20 [ 24.980105][ T12] kthread+0x318/0x420 [ 24.984768][ T12] ret_from_fork+0x24/0x30 [ 24.989698][ T12] [ 24.989698][ T12] other info that might help us debug this: [ 24.989698][ T12] [ 25.000004][ T12] Chain exists of: [ 25.000004][ T12] minor_rwsem --> iowarrior_mutex --> iowarrior_open_disc_lock [ 25.000004][ T12] [ 25.013444][ T12] Possible unsafe locking scenario: [ 25.013444][ T12] [ 25.020966][ T12] CPU0 CPU1 [ 25.026337][ T12] ---- ---- [ 25.031709][ T12] lock(iowarrior_open_disc_lock); [ 25.036891][ T12] lock(iowarrior_mutex); [ 25.043812][ T12] lock(iowarrior_open_disc_lock); [ 25.051506][ T12] lock(minor_rwsem); [ 25.057031][ T12] [ 25.057031][ T12] *** DEADLOCK *** [ 25.057031][ T12] [ 25.065160][ T12] 6 locks held by kworker/0:1/12: [ 25.070156][ T12] #0: 0000000016a35280 ((wq_completion)usb_hub_wq){+.+.}, at: process_one_work+0x827/0x1530 [ 25.080306][ T12] #1: 0000000001908f5f ((work_completion)(&hub->events)){+.+.}, at: process_one_work+0x85b/0x1530 [ 25.091009][ T12] #2: 00000000894e02c9 (&dev->mutex){....}, at: hub_event+0x17c/0x3640 [ 25.099355][ T12] #3: 00000000e2dd0256 (&dev->mutex){....}, at: usb_disconnect+0x91/0x8d0 [ 25.107929][ T12] #4: 0000000021bbb80a (&dev->mutex){....}, at: device_release_driver_internal+0x23/0x4c0 [ 25.117894][ T12] #5: 000000001d1989ef (iowarrior_open_disc_lock){+.+.}, at: iowarrior_disconnect+0x45/0x2c0 [ 25.128118][ T12] [ 25.128118][ T12] stack backtrace: [ 25.133989][ T12] CPU: 0 PID: 12 Comm: kworker/0:1 Not tainted 5.3.0-rc2+ #25 [ 25.141414][ T12] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 [ 25.151452][ T12] Workqueue: usb_hub_wq hub_event [ 25.156450][ T12] Call Trace: [ 25.159719][ T12] dump_stack+0xca/0x13e [ 25.163941][ T12] check_noncircular+0x345/0x3e0 [ 25.168860][ T12] ? print_circular_bug+0x1b0/0x1b0 [ 25.174036][ T12] ? __lock_acquire+0x2605/0x3b50 [ 25.179041][ T12] ? graph_lock+0x80/0x180 [ 25.183457][ T12] ? hlock_class+0x120/0x120 [ 25.188029][ T12] ? mark_lock+0xbc/0x1130 [ 25.192425][ T12] ? find_first_zero_bit+0x94/0xb0 [ 25.197516][ T12] __lock_acquire+0x1f7c/0x3b50 [ 25.202346][ T12] ? mark_held_locks+0xe0/0xe0 [ 25.207105][ T12] ? __mutex_lock+0x430/0x1360 [ 25.211851][ T12] lock_acquire+0x127/0x320 [ 25.216334][ T12] ? usb_deregister_dev+0x61/0x270 [ 25.221423][ T12] down_write+0x92/0x150 [ 25.225648][ T12] ? usb_deregister_dev+0x61/0x270 [ 25.230751][ T12] ? __down_timeout+0x2d0/0x2d0 [ 25.235597][ T12] usb_deregister_dev+0x61/0x270 [ 25.240515][ T12] iowarrior_disconnect+0xa8/0x2c0 [ 25.245605][ T12] usb_unbind_interface+0x1bd/0x8a0 [ 25.250780][ T12] ? usb_autoresume_device+0x60/0x60 [ 25.256045][ T12] device_release_driver_internal+0x404/0x4c0 [ 25.262097][ T12] bus_remove_device+0x2dc/0x4a0 [ 25.267027][ T12] device_del+0x420/0xb10 [ 25.271332][ T12] ? __device_links_no_driver+0x240/0x240 [ 25.277027][ T12] ? usb_remove_ep_devs+0x3e/0x80 [ 25.282031][ T12] ? remove_intf_ep_devs+0x13f/0x1d0 [ 25.287302][ T12] usb_disable_device+0x211/0x690 [ 25.292318][ T12] usb_disconnect+0x284/0x8d0 [ 25.296973][ T12] hub_event+0x1454/0x3640 [ 25.301442][ T12] ? find_held_lock+0x2d/0x110 [ 25.306199][ T12] ? mark_held_locks+0xe0/0xe0 [ 25.310942][ T12] ? hub_port_debounce+0x260/0x260 [ 25.316030][ T12] process_one_work+0x92b/0x1530 [ 25.320944][ T12] ? pwq_dec_nr_in_flight+0x310/0x310 [ 25.326288][ T12] ? do_raw_spin_lock+0x11a/0x280 [ 25.331284][ T12] worker_thread+0x96/0xe20 [ 25.335761][ T12] ? process_one_work+0x1530/0x1530 [ 25.340939][ T12] kthread+0x318/0x420 [ 25.344991][ T12] ? kthread_create_on_node+0xf0/0xf0 [ 25.350338][ T12] ret_from_fork+0x24/0x30 [ 25.365766][ T12] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 25.715161][ T12] usb 1-1: new low-speed USB device number 3 using dummy_hcd [ 26.075222][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 26.083384][ T12] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 26.093465][ T12] usb 1-1: config 0 has no interface number 0 [ 26.099555][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 26.109641][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 26.118674][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 26.127396][ T12] usb 1-1: config 0 descriptor?? [ 26.167232][ T12] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 26.366381][ T22] usb 1-1: USB disconnect, device number 3 [ 26.369420][ T1722] iowarrior 1-1:0.236: Error -19 while submitting URB [ 26.379638][ T22] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 26.735189][ T22] usb 1-1: new low-speed USB device number 4 using dummy_hcd [ 27.095228][ T22] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 27.103401][ T22] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 27.113474][ T22] usb 1-1: config 0 has no interface number 0 [ 27.119577][ T22] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 27.129679][ T22] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 27.138719][ T22] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 27.147558][ T22] usb 1-1: config 0 descriptor?? [ 27.187163][ T22] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 27.386026][ T22] usb 1-1: USB disconnect, device number 4 [ 27.394355][ T22] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 27.745175][ T22] usb 1-1: new low-speed USB device number 5 using dummy_hcd [ 28.105236][ T22] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 28.113407][ T22] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 28.123519][ T22] usb 1-1: config 0 has no interface number 0 [ 28.129635][ T22] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 28.139758][ T22] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 28.148808][ T22] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 28.157584][ T22] usb 1-1: config 0 descriptor?? [ 28.207679][ T22] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 28.406333][ T12] usb 1-1: USB disconnect, device number 5 [ 28.409415][ T1726] iowarrior 1-1:0.236: Error -19 while submitting URB [ 28.419585][ T12] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 28.775168][ T12] usb 1-1: new low-speed USB device number 6 using dummy_hcd [ 29.145194][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 29.153364][ T12] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 29.163444][ T12] usb 1-1: config 0 has no interface number 0 [ 29.169534][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 29.179618][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 29.188659][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 29.197364][ T12] usb 1-1: config 0 descriptor?? [ 29.237169][ T12] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 29.436081][ T22] usb 1-1: USB disconnect, device number 6 [ 29.439373][ T1730] iowarrior 1-1:0.236: Error -19 while submitting URB [ 29.449351][ T22] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 29.805172][ T22] usb 1-1: new low-speed USB device number 7 using dummy_hcd [ 30.165229][ T22] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 30.173445][ T22] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 30.183560][ T22] usb 1-1: config 0 has no interface number 0 [ 30.189668][ T22] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 30.199777][ T22] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 30.208834][ T22] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 30.217879][ T22] usb 1-1: config 0 descriptor?? [ 30.257182][ T22] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 30.456148][ T12] usb 1-1: USB disconnect, device number 7 [ 30.457791][ T1732] iowarrior 1-1:0.236: Error -19 while submitting URB [ 30.470746][ T12] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 30.825160][ T12] usb 1-1: new low-speed USB device number 8 using dummy_hcd [ 31.185233][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 31.193448][ T12] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 31.203543][ T12] usb 1-1: config 0 has no interface number 0 [ 31.209682][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 31.219871][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 31.228921][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 31.237693][ T12] usb 1-1: config 0 descriptor?? [ 31.277173][ T12] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 31.476079][ T12] usb 1-1: USB disconnect, device number 8 [ 31.479059][ T1734] iowarrior 1-1:0.236: Error -19 while submitting URB [ 31.490577][ T12] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 31.845220][ T12] usb 1-1: new low-speed USB device number 9 using dummy_hcd [ 32.215215][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 32.223473][ T12] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 32.233580][ T12] usb 1-1: config 0 has no interface number 0 [ 32.239675][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 32.249778][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 32.258851][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 32.267720][ T12] usb 1-1: config 0 descriptor?? [ 32.307241][ T12] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 32.506198][ T12] usb 1-1: USB disconnect, device number 9 [ 32.509177][ T1737] iowarrior 1-1:0.236: Error -19 while submitting URB [ 32.519553][ T12] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 32.875136][ T12] usb 1-1: new low-speed USB device number 10 using dummy_hcd [ 33.235199][ T12] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 33.243599][ T12] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 33.253704][ T12] usb 1-1: config 0 has no interface number 0 [ 33.259869][ T12] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 33.270197][ T12] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 33.279237][ T12] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 33.287956][ T12] usb 1-1: config 0 descriptor?? [ 33.337264][ T12] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 33.536171][ T22] usb 1-1: USB disconnect, device number 10 [ 33.539317][ T1739] iowarrior 1-1:0.236: Error -19 while submitting URB [ 33.549573][ T22] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected [ 33.905207][ T22] usb 1-1: new low-speed USB device number 11 using dummy_hcd [ 34.265262][ T22] usb 1-1: config 0 has an invalid interface number: 236 but max is 0 [ 34.273563][ T22] usb 1-1: config 0 has an invalid descriptor of length 0, skipping remainder of the config [ 34.283749][ T22] usb 1-1: config 0 has no interface number 0 [ 34.289867][ T22] usb 1-1: config 0 interface 236 altsetting 0 endpoint 0x81 is Bulk; changing to Interrupt [ 34.300189][ T22] usb 1-1: New USB device found, idVendor=07c0, idProduct=1501, bcdDevice=74.a0 [ 34.309260][ T22] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 34.318134][ T22] usb 1-1: config 0 descriptor?? [ 34.357514][ T22] iowarrior 1-1:0.236: IOWarrior product=0x1501, serial= interface=236 now attached to iowarrior0 executing program [ 34.556232][ T12] usb 1-1: USB disconnect, device number 11 [ 34.559282][ T1741] iowarrior 1-1:0.236: Error -19 while submitting URB [ 34.571272][ T12] iowarrior 1-1:0.236: I/O-Warror #0 now disconnected