Warning: Permanently added '[localhost]:12761' (ECDSA) to the list of known hosts. 2023/05/28 13:16:37 ignoring optional flag "sandboxArg"="0" 2023/05/28 13:16:37 parsed 1 programs [ 71.337018][ T40] kauditd_printk_skb: 5 callbacks suppressed [ 71.337026][ T40] audit: type=1400 audit(1685279797.950:202): avc: denied { getattr } for pid=5301 comm="syz-execprog" path="user:[4026531837]" dev="nsfs" ino=4026531837 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:nsfs_t tclass=file permissive=1 [ 71.347092][ T40] audit: type=1400 audit(1685279797.950:203): avc: denied { read } for pid=5301 comm="syz-execprog" dev="nsfs" ino=4026531837 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:nsfs_t tclass=file permissive=1 [ 71.355204][ T40] audit: type=1400 audit(1685279797.950:204): avc: denied { open } for pid=5301 comm="syz-execprog" path="user:[4026531837]" dev="nsfs" ino=4026531837 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:nsfs_t tclass=file permissive=1 [ 71.366222][ T40] audit: type=1400 audit(1685279797.980:205): avc: denied { mounton } for pid=5307 comm="syz-executor" path="/syzcgroup/unified" dev="cgroup2" ino=1 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:cgroup_t tclass=dir permissive=1 [ 71.368131][ T5307] cgroup: Unknown subsys name 'net' [ 71.375042][ T40] audit: type=1400 audit(1685279797.980:206): avc: denied { mount } for pid=5307 comm="syz-executor" name="/" dev="cgroup2" ino=1 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:cgroup_t tclass=filesystem permissive=1 [ 71.380135][ T5307] cgroup: Unknown subsys name 'rlimit' 2023/05/28 13:16:38 executed programs: 0 [ 71.390491][ T40] audit: type=1400 audit(1685279798.000:207): avc: denied { mounton } for pid=5307 comm="syz-executor" path="/proc/sys/fs/binfmt_misc" dev="binfmt_misc" ino=1 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:binfmt_misc_fs_t tclass=dir permissive=1 [ 71.401332][ T40] audit: type=1400 audit(1685279798.000:208): avc: denied { mount } for pid=5307 comm="syz-executor" name="/" dev="binfmt_misc" ino=1 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:binfmt_misc_fs_t tclass=filesystem permissive=1 [ 74.553676][ T63] Bluetooth: hci0: Opcode 0x c03 failed: -110 [ 76.637097][ T5134] Bluetooth: hci0: unexpected cc 0x0c03 length: 249 > 1 [ 76.640523][ T5134] Bluetooth: hci0: unexpected cc 0x1003 length: 249 > 9 [ 76.643209][ T4577] Bluetooth: hci0: unexpected cc 0x1001 length: 249 > 9 [ 76.647254][ T4577] Bluetooth: hci0: unexpected cc 0x0c23 length: 249 > 4 [ 76.650771][ T5134] Bluetooth: hci0: unexpected cc 0x0c25 length: 249 > 3 [ 76.654947][ T5134] Bluetooth: hci0: unexpected cc 0x0c38 length: 249 > 2 [ 76.664201][ T40] audit: type=1400 audit(1685279803.280:209): avc: denied { mounton } for pid=5356 comm="syz-executor.0" path="/" dev="sda1" ino=2 scontext=root:sysadm_r:sysadm_t tcontext=system_u:object_r:root_t tclass=dir permissive=1 [ 76.739364][ T5356] chnl_net:caif_netlink_parms(): no params data found [ 76.806491][ T5356] bridge0: port 1(bridge_slave_0) entered blocking state [ 76.809517][ T5356] bridge0: port 1(bridge_slave_0) entered disabled state [ 76.812848][ T5356] bridge_slave_0: entered allmulticast mode [ 76.815942][ T5356] bridge_slave_0: entered promiscuous mode [ 76.819399][ T5356] bridge0: port 2(bridge_slave_1) entered blocking state [ 76.822203][ T5356] bridge0: port 2(bridge_slave_1) entered disabled state [ 76.825512][ T5356] bridge_slave_1: entered allmulticast mode [ 76.828876][ T5356] bridge_slave_1: entered promiscuous mode [ 76.861913][ T5356] bond0: (slave bond_slave_0): Enslaving as an active interface with an up link [ 76.867848][ T5356] bond0: (slave bond_slave_1): Enslaving as an active interface with an up link [ 76.896168][ T5356] team0: Port device team_slave_0 added [ 76.901077][ T5356] team0: Port device team_slave_1 added [ 76.930496][ T5356] batman_adv: batadv0: Adding interface: batadv_slave_0 [ 76.933639][ T5356] batman_adv: batadv0: The MTU of interface batadv_slave_0 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 76.945334][ T5356] batman_adv: batadv0: Not using interface batadv_slave_0 (retrying later): interface not active [ 76.950913][ T5356] batman_adv: batadv0: Adding interface: batadv_slave_1 [ 76.954759][ T5356] batman_adv: batadv0: The MTU of interface batadv_slave_1 is too small (1500) to handle the transport of batman-adv packets. Packets going over this interface will be fragmented on layer2 which could impact the performance. Setting the MTU to 1560 would solve the problem. [ 76.965973][ T5356] batman_adv: batadv0: Not using interface batadv_slave_1 (retrying later): interface not active [ 76.996283][ T5356] hsr_slave_0: entered promiscuous mode [ 76.999251][ T5356] hsr_slave_1: entered promiscuous mode [ 77.056613][ T5356] bridge0: port 2(bridge_slave_1) entered blocking state [ 77.059713][ T5356] bridge0: port 2(bridge_slave_1) entered forwarding state [ 77.063053][ T5356] bridge0: port 1(bridge_slave_0) entered blocking state [ 77.065699][ T5356] bridge0: port 1(bridge_slave_0) entered forwarding state [ 77.091880][ T5356] 8021q: adding VLAN 0 to HW filter on device bond0 [ 77.099996][ T5152] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready [ 77.103935][ T5152] bridge0: port 1(bridge_slave_0) entered disabled state [ 77.107683][ T5152] bridge0: port 2(bridge_slave_1) entered disabled state [ 77.111198][ T5152] IPv6: ADDRCONF(NETDEV_CHANGE): bond0: link becomes ready [ 77.122132][ T5356] 8021q: adding VLAN 0 to HW filter on device team0 [ 77.129135][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_0: link becomes ready [ 77.132947][ T39] bridge0: port 1(bridge_slave_0) entered blocking state [ 77.136135][ T39] bridge0: port 1(bridge_slave_0) entered forwarding state [ 77.143105][ T5364] IPv6: ADDRCONF(NETDEV_CHANGE): bridge_slave_1: link becomes ready [ 77.146768][ T5364] bridge0: port 2(bridge_slave_1) entered blocking state [ 77.149818][ T5364] bridge0: port 2(bridge_slave_1) entered forwarding state [ 77.163871][ T4608] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_0: link becomes ready [ 77.167194][ T4608] IPv6: ADDRCONF(NETDEV_CHANGE): team0: link becomes ready [ 77.174519][ T5364] IPv6: ADDRCONF(NETDEV_CHANGE): team_slave_1: link becomes ready [ 77.178515][ T5364] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_0: link becomes ready [ 77.184516][ T5356] hsr0: Slave B (hsr_slave_1) is not up; please bring it up to get a fully working HSR network [ 77.188406][ T5356] IPv6: ADDRCONF(NETDEV_CHANGE): hsr0: link becomes ready [ 77.195876][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): hsr_slave_1: link becomes ready [ 77.202468][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan0: link becomes ready [ 77.205247][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): vxcan1: link becomes ready [ 77.211094][ T5356] 8021q: adding VLAN 0 to HW filter on device batadv0 [ 77.230854][ T40] audit: type=1400 audit(1685279803.840:210): avc: denied { sys_module } for pid=5356 comm="syz-executor.0" capability=16 scontext=root:sysadm_r:sysadm_t tcontext=root:sysadm_r:sysadm_t tclass=capability permissive=1 [ 77.454268][ T23] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_virt_wifi: link becomes ready [ 77.462171][ T4608] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_vlan: link becomes ready [ 77.465720][ T4608] IPv6: ADDRCONF(NETDEV_CHANGE): vlan0: link becomes ready [ 77.468684][ T4608] IPv6: ADDRCONF(NETDEV_CHANGE): vlan1: link becomes ready [ 77.473039][ T5356] veth0_vlan: entered promiscuous mode [ 77.480367][ T5356] veth1_vlan: entered promiscuous mode [ 77.497503][ T23] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan0: link becomes ready [ 77.501394][ T23] IPv6: ADDRCONF(NETDEV_CHANGE): macvlan1: link becomes ready [ 77.505747][ T23] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_macvtap: link becomes ready [ 77.510889][ T5356] veth0_macvtap: entered promiscuous mode [ 77.515477][ T5356] veth1_macvtap: entered promiscuous mode [ 77.524070][ T5356] batman_adv: batadv0: Interface activated: batadv_slave_0 [ 77.527415][ T5145] IPv6: ADDRCONF(NETDEV_CHANGE): veth0_to_batadv: link becomes ready [ 77.531597][ T5145] IPv6: ADDRCONF(NETDEV_CHANGE): macvtap0: link becomes ready [ 77.538350][ T5356] batman_adv: batadv0: Interface activated: batadv_slave_1 [ 77.541791][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): veth1_to_batadv: link becomes ready [ 77.575285][ T10] wlan0: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 77.578736][ T10] wlan0: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 77.583009][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 77.595913][ T10] wlan1: Created IBSS using preconfigured BSSID 50:50:50:50:50:50 [ 77.598839][ T10] wlan1: Creating new IBSS network, BSSID 50:50:50:50:50:50 [ 77.602426][ T39] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 77.607460][ T40] audit: type=1400 audit(1685279804.220:211): avc: denied { mounton } for pid=5356 comm="syz-executor.0" path="/dev/binderfs" dev="devtmpfs" ino=2389 scontext=root:sysadm_r:sysadm_t tcontext=root:object_r:device_t tclass=dir permissive=1 2023/05/28 13:16:44 executed programs: 1 [ 78.103817][ T5384] floppy1: reschedule timeout lock fdc [ 78.104229][ T10] [ 78.106901][ T5384] floppy1: setting NEWCHANGE in poll_drive [ 78.107776][ T10] floppy driver state [ 78.107783][ T10] ------------------- [ 78.107788][ T10] now=4294944915 last interrupt=4294944912 diff=3 last called handler=main_command_interrupt [ 78.118791][ T10] timeout_message=lock fdc [ 78.120420][ T10] last output bytes: [ 78.122066][ T10] 0 90 4294944909 [ 78.123817][ T10] 1 90 4294944909 [ 78.125186][ T10] 2 90 4294944909 [ 78.126544][ T10] 12 90 4294944909 [ 78.128037][ T10] 1b 90 4294944909 [ 78.129397][ T10] ff 90 4294944909 [ 78.130725][ T10] 12 80 4294944912 [ 78.132068][ T10] 3 90 4294944912 [ 78.134102][ T10] 3 80 4294944912 [ 78.135771][ T10] a1 90 4294944912 [ 78.137276][ T10] 1e 90 4294944912 [ 78.138776][ T10] e6 80 4294944912 [ 78.140192][ T10] 1 90 4294944912 [ 78.141678][ T10] 0 90 4294944912 [ 78.143256][ T10] 0 90 4294944912 [ 78.145405][ T10] 1 90 4294944912 [ 78.147031][ T10] 2 90 4294944912 [ 78.148666][ T10] 24 90 4294944912 [ 78.150263][ T10] 1b 90 4294944912 [ 78.151867][ T10] ff 90 4294944912 [ 78.153861][ T10] last result at 4294944913 [ 78.155830][ T10] last redo_fd_request at 4294944913 [ 78.158192][ T10] status=80 [ 78.159596][ T10] fdc_busy=1 [ 78.161043][ T10] floppy_work.func=floppy_work_workfn [ 78.163578][ T10] cont=ffffffff8ad02f20 [ 78.165445][ T10] current_req=0000000000000000 [ 78.167519][ T10] command_status=-1 [ 78.169213][ T10] [ 78.170251][ T10] floppy1: floppy timeout called [ 78.193300][ T5387] floppy1: reschedule timeout lock fdc [ 78.193348][ T10] [ 78.196205][ T5387] floppy1: setting NEWCHANGE in poll_drive [ 78.196972][ T10] floppy driver state [ 78.196976][ T10] ------------------- [ 78.202953][ T10] now=4294944924 last interrupt=4294944912 diff=12 last called handler=main_command_interrupt [ 78.207150][ T10] timeout_message=lock fdc [ 78.209008][ T10] last output bytes: [ 78.210680][ T10] 0 90 4294944909 [ 78.212006][ T10] 1 90 4294944909 [ 78.213527][ T10] 2 90 4294944909 [ 78.215066][ T10] 12 90 4294944909 [ 78.216539][ T10] 1b 90 4294944909 [ 78.218071][ T10] ff 90 4294944909 [ 78.219631][ T10] 12 80 4294944912 [ 78.221139][ T10] 3 90 4294944912 [ 78.222592][ T10] 3 80 4294944912 [ 78.224293][ T10] a1 90 4294944912 [ 78.225913][ T10] 1e 90 4294944912 [ 78.227448][ T10] e6 80 4294944912 [ 78.229009][ T10] 1 90 4294944912 [ 78.230470][ T10] 0 90 4294944912 [ 78.231949][ T10] 0 90 4294944912 [ 78.234514][ T10] 1 90 4294944912 [ 78.236140][ T10] 2 90 4294944912 [ 78.237777][ T10] 24 90 4294944912 [ 78.239368][ T10] 1b 90 4294944912 [ 78.240963][ T10] ff 90 4294944912 [ 78.242560][ T10] last result at 4294944913 [ 78.245141][ T10] last redo_fd_request at 4294944921 [ 78.247412][ T10] status=80 [ 78.248812][ T10] fdc_busy=1 [ 78.250207][ T10] floppy_work.func=floppy_work_workfn [ 78.252439][ T10] cont=ffffffff8ad02f20 [ 78.254269][ T10] current_req=0000000000000000 [ 78.256029][ T10] command_status=-1 [ 78.257487][ T10] [ 78.258444][ T10] floppy1: floppy timeout called [ 78.282107][ T5390] floppy1: reschedule timeout lock fdc [ 78.282152][ T10] [ 78.284695][ T5390] floppy1: setting NEWCHANGE in poll_drive [ 78.288640][ T10] floppy driver state [ 78.290432][ T10] ------------------- [ 78.292158][ T10] now=4294944933 last interrupt=4294944912 diff=21 last called handler=main_command_interrupt [ 78.296704][ T10] timeout_message=lock fdc [ 78.298363][ T10] last output bytes: [ 78.299721][ T10] 0 90 4294944909 [ 78.301275][ T10] 1 90 4294944909 [ 78.302872][ T10] 2 90 4294944909 [ 78.304606][ T10] 12 90 4294944909 [ 78.306203][ T10] 1b 90 4294944909 [ 78.307579][ T10] ff 90 4294944909 [ 78.309174][ T10] 12 80 4294944912 [ 78.310775][ T10] 3 90 4294944912 [ 78.312403][ T10] 3 80 4294944912 [ 78.315845][ T10] a1 90 4294944912 [ 78.317584][ T10] 1e 90 4294944912 [ 78.319125][ T10] e6 80 4294944912 [ 78.320758][ T10] 1 90 4294944912 [ 78.322419][ T10] 0 90 4294944912 [ 78.324349][ T10] 0 90 4294944912 [ 78.326610][ T10] 1 90 4294944912 [ 78.328083][ T10] 2 90 4294944912 [ 78.329546][ T10] 24 90 4294944912 [ 78.331075][ T10] 1b 90 4294944912 [ 78.332827][ T10] ff 90 4294944912 [ 78.334436][ T10] last result at 4294944913 [ 78.336489][ T10] last redo_fd_request at 4294944930 [ 78.338874][ T10] status=80 [ 78.340322][ T10] fdc_busy=1 [ 78.341846][ T10] floppy_work.func=floppy_work_workfn [ 78.344304][ T10] cont=ffffffff8ad02f20 [ 78.346130][ T10] current_req=0000000000000000 [ 78.348172][ T10] command_status=-1 [ 78.349817][ T10] [ 78.350949][ T10] floppy1: floppy timeout called [ 78.365150][ T5393] floppy1: reschedule timeout lock fdc [ 78.367631][ T5153] [ 78.368702][ T5153] floppy driver state [ 78.369638][ T5393] floppy1: setting NEWCHANGE in poll_drive [ 78.370477][ T5153] ------------------- [ 78.370484][ T5153] now=4294944941 last interrupt=4294944912 diff=29 last called handler=main_command_interrupt [ 78.379538][ T5153] timeout_message=lock fdc [ 78.381551][ T5153] last output bytes: [ 78.383177][ T5153] 0 90 4294944909 [ 78.385175][ T5153] 1 90 4294944909 [ 78.386743][ T5153] 2 90 4294944909 [ 78.388401][ T5153] 12 90 4294944909 [ 78.390052][ T5153] 1b 90 4294944909 [ 78.391667][ T5153] ff 90 4294944909 [ 78.393325][ T5153] 12 80 4294944912 [ 78.395431][ T5153] 3 90 4294944912 [ 78.397134][ T5153] 3 80 4294944912 [ 78.398804][ T5153] a1 90 4294944912 [ 78.400430][ T5153] 1e 90 4294944912 [ 78.402081][ T5153] e6 80 4294944912 [ 78.404265][ T5153] 1 90 4294944912 [ 78.405953][ T5153] 0 90 4294944912 [ 78.407596][ T5153] 0 90 4294944912 [ 78.409272][ T5153] 1 90 4294944912 [ 78.410908][ T5153] 2 90 4294944912 [ 78.412526][ T5153] 24 90 4294944912 [ 78.414528][ T5153] 1b 90 4294944912 [ 78.416210][ T5153] ff 90 4294944912 [ 78.417727][ T5153] last result at 4294944913 [ 78.419509][ T5153] last redo_fd_request at 4294944940 [ 78.421882][ T5153] status=80 [ 78.423244][ T5153] fdc_busy=1 [ 78.424586][ T5153] floppy_work.func=floppy_work_workfn [ 78.426547][ T5153] cont=ffffffff8ad02f20 [ 78.428039][ T5153] current_req=0000000000000000 [ 78.429824][ T5153] command_status=-1 [ 78.431211][ T5153] [ 78.432189][ T5153] floppy1: floppy timeout called [ 78.449693][ T5396] floppy1: reschedule timeout lock fdc [ 78.451681][ T5396] floppy1: setting NEWCHANGE in poll_drive [ 78.453595][ T5153] [ 78.455026][ T5153] floppy driver state [ 78.456634][ T5153] ------------------- [ 78.458464][ T5153] now=4294944950 last interrupt=4294944912 diff=38 last called handler=main_command_interrupt [ 78.462904][ T5153] timeout_message=lock fdc [ 78.464744][ T5153] last output bytes: [ 78.466496][ T5153] 0 90 4294944909 [ 78.468101][ T5153] 1 90 4294944909 [ 78.469806][ T5153] 2 90 4294944909 [ 78.471476][ T5153] 12 90 4294944909 [ 78.473211][ T5153] 1b 90 4294944909 [ 78.475049][ T5153] ff 90 4294944909 [ 78.476723][ T5153] 12 80 4294944912 [ 78.478104][ T5153] 3 90 4294944912 [ 78.479551][ T5153] 3 80 4294944912 [ 78.481133][ T5153] a1 90 4294944912 [ 78.482712][ T5153] 1e 90 4294944912 [ 78.484263][ T5153] e6 80 4294944912 [ 78.485678][ T5153] 1 90 4294944912 [ 78.487030][ T5153] 0 90 4294944912 [ 78.488397][ T5153] 0 90 4294944912 [ 78.489831][ T5153] 1 90 4294944912 [ 78.491296][ T5153] 2 90 4294944912 [ 78.492684][ T5153] 24 90 4294944912 [ 78.494335][ T5153] 1b 90 4294944912 [ 78.495717][ T5153] ff 90 4294944912 [ 78.497209][ T5153] last result at 4294944913 [ 78.498902][ T5153] last redo_fd_request at 4294944948 [ 78.502089][ T5153] status=80 [ 78.503618][ T5153] fdc_busy=1 [ 78.505077][ T5153] floppy_work.func=floppy_work_workfn [ 78.507318][ T5153] cont=ffffffff8ad02f20 [ 78.508801][ T5153] current_req=0000000000000000 [ 78.510466][ T5153] command_status=-1 [ 78.511812][ T5153] [ 78.512696][ T5153] floppy1: floppy timeout called [ 78.526255][ T5399] floppy1: reschedule timeout lock fdc [ 78.528412][ T5399] floppy1: setting NEWCHANGE in poll_drive [ 78.530492][ T5153] [ 78.531274][ T5153] floppy driver state [ 78.532599][ T5153] ------------------- [ 78.534248][ T5153] now=4294944958 last interrupt=4294944912 diff=46 last called handler=main_command_interrupt [ 78.538307][ T5153] timeout_message=lock fdc [ 78.539877][ T5153] last output bytes: [ 78.541266][ T5153] 0 90 4294944909 [ 78.542789][ T5153] 1 90 4294944909 [ 78.544526][ T5153] 2 90 4294944909 [ 78.546073][ T5153] 12 90 4294944909 [ 78.547498][ T5153] 1b 90 4294944909 [ 78.548884][ T5153] ff 90 4294944909 [ 78.550306][ T5153] 12 80 4294944912 [ 78.551847][ T5153] 3 90 4294944912 [ 78.553422][ T5153] 3 80 4294944912 [ 78.556003][ T5153] a1 90 4294944912 [ 78.557710][ T5153] 1e 90 4294944912 [ 78.559365][ T5153] e6 80 4294944912 [ 78.560894][ T5153] 1 90 4294944912 [ 78.562501][ T5153] 0 90 4294944912 [ 78.564259][ T5153] 0 90 4294944912 [ 78.565751][ T5153] 1 90 4294944912 [ 78.567181][ T5153] 2 90 4294944912 [ 78.568604][ T5153] 24 90 4294944912 [ 78.570082][ T5153] 1b 90 4294944912 [ 78.571607][ T5153] ff 90 4294944912 [ 78.573282][ T5153] last result at 4294944913 [ 78.575646][ T5153] last redo_fd_request at 4294944956 [ 78.577590][ T5153] status=80 [ 78.578715][ T5153] fdc_busy=1 [ 78.579873][ T5153] floppy_work.func=floppy_work_workfn [ 78.581991][ T5153] cont=ffffffff8ad02f20 [ 78.583796][ T5153] current_req=0000000000000000 [ 78.585552][ T5153] command_status=-1 [ 78.587086][ T5153] [ 78.588210][ T5153] floppy1: floppy timeout called [ 78.608859][ T5402] floppy1: reschedule timeout lock fdc [ 78.608984][ T5219] [ 78.611497][ T5402] floppy1: setting NEWCHANGE in poll_drive [ 78.612419][ T5219] floppy driver state [ 78.612426][ T5219] ------------------- [ 78.618858][ T5219] now=4294944966 last interrupt=4294944912 diff=54 last called handler=main_command_interrupt [ 78.623583][ T5219] timeout_message=lock fdc [ 78.625503][ T5219] last output bytes: [ 78.626992][ T5219] 0 90 4294944909 [ 78.628209][ T5219] 1 90 4294944909 [ 78.629762][ T5219] 2 90 4294944909 [ 78.631424][ T5219] 12 90 4294944909 [ 78.633119][ T5219] 1b 90 4294944909 [ 78.635472][ T5219] ff 90 4294944909 [ 78.636922][ T5219] 12 80 4294944912 [ 78.638459][ T5219] 3 90 4294944912 [ 78.639823][ T5219] 3 80 4294944912 [ 78.641216][ T5219] a1 90 4294944912 [ 78.642780][ T5219] 1e 90 4294944912 [ 78.644610][ T5219] e6 80 4294944912 [ 78.646018][ T5219] 1 90 4294944912 [ 78.647310][ T5219] 0 90 4294944912 [ 78.648648][ T5219] 0 90 4294944912 [ 78.649985][ T5219] 1 90 4294944912 [ 78.651309][ T5219] 2 90 4294944912 [ 78.652646][ T5219] 24 90 4294944912 [ 78.654515][ T5219] 1b 90 4294944912 [ 78.655937][ T5219] ff 90 4294944912 [ 78.657286][ T5219] last result at 4294944913 [ 78.659002][ T5219] last redo_fd_request at 4294944963 [ 78.661165][ T5219] status=80 [ 78.662305][ T5219] fdc_busy=1 [ 78.663578][ T5219] floppy_work.func=floppy_work_workfn [ 78.665568][ T5219] cont=ffffffff8ad02f20 [ 78.667287][ T5219] current_req=0000000000000000 [ 78.669243][ T5219] command_status=-1 [ 78.670810][ T5219] [ 78.671878][ T5219] floppy1: floppy timeout called [ 78.687254][ T5405] floppy1: reschedule timeout lock fdc [ 78.688066][ T5219] [ 78.689655][ T5405] floppy1: setting NEWCHANGE in poll_drive [ 78.690510][ T5219] floppy driver state [ 78.690515][ T5219] ------------------- [ 78.696078][ T5219] now=4294944974 last interrupt=4294944912 diff=62 last called handler=main_command_interrupt [ 78.700034][ T5219] timeout_message=lock fdc [ 78.701815][ T5219] last output bytes: [ 78.703380][ T5219] 0 90 4294944909 [ 78.704958][ T5219] 1 90 4294944909 [ 78.706514][ T5219] 2 90 4294944909 [ 78.707898][ T5219] 12 90 4294944909 [ 78.709388][ T5219] 1b 90 4294944909 [ 78.710940][ T5219] ff 90 4294944909 [ 78.712298][ T5219] 12 80 4294944912 [ 78.714639][ T5134] Bluetooth: hci0: command 0x0409 tx timeout [ 78.714755][ T5219] 3 90 4294944912 [ 78.718761][ T5219] 3 80 4294944912 [ 78.720138][ T5219] a1 90 4294944912 [ 78.721504][ T5219] 1e 90 4294944912 [ 78.722876][ T5219] e6 80 4294944912 [ 78.724517][ T5219] 1 90 4294944912 [ 78.726026][ T5219] 0 90 4294944912 [ 78.727563][ T5219] 0 90 4294944912 [ 78.729031][ T5219] 1 90 4294944912 [ 78.730444][ T5219] 2 90 4294944912 [ 78.731902][ T5219] 24 90 4294944912 [ 78.733400][ T5219] 1b 90 4294944912 [ 78.735272][ T5219] ff 90 4294944912 [ 78.736690][ T5219] last result at 4294944913 [ 78.738669][ T5219] last redo_fd_request at 4294944972 [ 78.740931][ T5219] status=80 [ 78.742134][ T5219] fdc_busy=1 [ 78.743330][ T5219] floppy_work.func=floppy_work_workfn [ 78.745413][ T5219] cont=ffffffff8ad02f20 [ 78.747005][ T5219] current_req=0000000000000000 [ 78.748814][ T5219] command_status=-1 [ 78.750405][ T5219] [ 78.751334][ T5219] floppy1: floppy timeout called [ 78.768413][ T5408] floppy1: reschedule timeout lock fdc [ 78.768472][ T5153] [ 78.770863][ T5408] floppy1: setting NEWCHANGE in poll_drive [ 78.775139][ T5153] floppy driver state [ 78.776628][ T5153] ------------------- [ 78.778088][ T5153] now=4294944982 last interrupt=4294944912 diff=70 last called handler=main_command_interrupt [ 78.782341][ T5153] timeout_message=lock fdc [ 78.784735][ T5153] last output bytes: [ 78.786491][ T5153] 0 90 4294944909 [ 78.788074][ T5153] 1 90 4294944909 [ 78.789701][ T5153] 2 90 4294944909 [ 78.791208][ T5153] 12 90 4294944909 [ 78.792516][ T5153] 1b 90 4294944909 [ 78.794638][ T5153] ff 90 4294944909 [ 78.796293][ T5153] 12 80 4294944912 [ 78.797783][ T5153] 3 90 4294944912 [ 78.799186][ T5153] 3 80 4294944912 [ 78.800804][ T5153] a1 90 4294944912 [ 78.802367][ T5153] 1e 90 4294944912 [ 78.803897][ T5153] e6 80 4294944912 [ 78.805524][ T5153] 1 90 4294944912 [ 78.807084][ T5153] 0 90 4294944912 [ 78.808404][ T5153] 0 90 4294944912 [ 78.809715][ T5153] 1 90 4294944912 [ 78.811025][ T5153] 2 90 4294944912 [ 78.812374][ T5153] 24 90 4294944912 [ 78.814031][ T5153] 1b 90 4294944912 [ 78.815624][ T5153] ff 90 4294944912 [ 78.817281][ T5153] last result at 4294944913 [ 78.819207][ T5153] last redo_fd_request at 4294944980 [ 78.821279][ T5153] status=80 [ 78.822459][ T5153] fdc_busy=1 [ 78.823699][ T5153] floppy_work.func=floppy_work_workfn [ 78.825622][ T5153] cont=ffffffff8ad02f20 [ 78.827069][ T5153] current_req=0000000000000000 [ 78.828750][ T5153] command_status=-1 [ 78.830105][ T5153] [ 78.831134][ T5153] floppy1: floppy timeout called [ 78.847729][ T5411] floppy1: reschedule timeout lock fdc [ 78.849991][ T5153] [ 78.850957][ T5153] floppy driver state [ 78.852650][ T5153] ------------------- [ 78.853614][ T5411] floppy1: setting NEWCHANGE in poll_drive [ 78.854308][ T5153] now=4294944990 last interrupt=4294944912 diff=78 last called handler=main_command_interrupt [ 78.860872][ T5153] timeout_message=lock fdc [ 78.862793][ T5153] last output bytes: [ 78.864643][ T5153] 0 90 4294944909 [ 78.866284][ T5153] 1 90 4294944909 [ 78.867903][ T5153] 2 90 4294944909 [ 78.869469][ T5153] 12 90 4294944909 [ 78.870895][ T5153] 1b 90 4294944909 [ 78.872290][ T5153] ff 90 4294944909 [ 78.874002][ T5153] 12 80 4294944912 [ 78.875465][ T5153] 3 90 4294944912 [ 78.876877][ T5153] 3 80 4294944912 [ 78.878301][ T5153] a1 90 4294944912 [ 78.879737][ T5153] 1e 90 4294944912 [ 78.881247][ T5153] e6 80 4294944912 [ 78.882786][ T5153] 1 90 4294944912 [ 78.884641][ T5153] 0 90 4294944912 [ 78.886225][ T5153] 0 90 4294944912 [ 78.887814][ T5153] 1 90 4294944912 [ 78.889375][ T5153] 2 90 4294944912 [ 78.890902][ T5153] 24 90 4294944912 [ 78.892432][ T5153] 1b 90 4294944912 [ 78.894126][ T5153] ff 90 4294944912 [ 78.895750][ T5153] last result at 4294944913 [ 78.897852][ T5153] last redo_fd_request at 4294944987 [ 78.901165][ T5153] status=80 [ 78.902492][ T5153] fdc_busy=1 [ 78.904059][ T5153] floppy_work.func=floppy_work_workfn [ 78.906500][ T5153] cont=ffffffff8ad02f20 [ 78.908192][ T5153] current_req=0000000000000000 [ 78.910269][ T5153] command_status=-1 [ 78.911932][ T5153] [ 78.913040][ T5153] floppy1: floppy timeout called [ 78.929233][ T5414] floppy1: reschedule timeout lock fdc [ 78.929331][ T5153] [ 78.931719][ T5414] floppy1: setting NEWCHANGE in poll_drive [ 78.932715][ T5153] floppy driver state [ 78.937171][ T5153] ------------------- [ 78.938995][ T5153] now=4294944998 last interrupt=4294944912 diff=86 last called handler=main_command_interrupt [ 78.943526][ T5153] timeout_message=lock fdc [ 78.950131][ T5153] last output bytes: [ 78.951833][ T5153] 0 90 4294944909 [ 78.953223][ T5153] 1 90 4294944909 [ 78.954939][ T5153] 2 90 4294944909 [ 78.956516][ T5153] 12 90 4294944909 [ 78.958257][ T5153] 1b 90 4294944909 [ 78.959966][ T5153] ff 90 4294944909 [ 78.961727][ T5153] 12 80 4294944912 [ 78.963621][ T5153] 3 90 4294944912 [ 78.965342][ T5153] 3 80 4294944912 [ 78.966927][ T5153] a1 90 4294944912 [ 78.968617][ T5153] 1e 90 4294944912 [ 78.970199][ T5153] e6 80 4294944912 [ 78.971669][ T5153] 1 90 4294944912 [ 78.973286][ T5153] 0 90 4294944912 [ 78.974756][ T5153] 0 90 4294944912 [ 78.976303][ T5153] 1 90 4294944912 [ 78.977866][ T5153] 2 90 4294944912 [ 78.979164][ T5153] 24 90 4294944912 [ 78.980469][ T5153] 1b 90 4294944912 [ 78.981882][ T5153] ff 90 4294944912 [ 78.983211][ T5153] last result at 4294944913 [ 78.985198][ T5153] last redo_fd_request at 4294944996 [ 78.987301][ T5153] status=80 [ 78.988421][ T5153] fdc_busy=1 [ 78.989598][ T5153] floppy_work.func=floppy_work_workfn [ 78.991844][ T5153] cont=ffffffff8ad02f20 [ 78.993729][ T5153] current_req=0000000000000000 [ 78.995760][ T5153] command_status=-1 [ 78.997400][ T5153] [ 78.998476][ T5153] floppy1: floppy timeout called [ 79.014896][ T5417] floppy1: reschedule timeout lock fdc [ 79.017135][ T5153] [ 79.018139][ T5153] floppy driver state [ 79.018519][ T5417] floppy1: setting NEWCHANGE in poll_drive [ 79.019734][ T5153] ------------------- [ 79.019740][ T5153] now=4294945006 last interrupt=4294944912 diff=94 last called handler=main_command_interrupt [ 79.019764][ T5153] timeout_message=lock fdc [ 79.019770][ T5153] last output bytes: [ 79.019774][ T5153] 0 90 4294944909 [ 79.019783][ T5153] 1 90 4294944909 [ 79.035522][ T5153] 2 90 4294944909 [ 79.037241][ T5153] 12 90 4294944909 [ 79.038912][ T5153] 1b 90 4294944909 [ 79.040618][ T5153] ff 90 4294944909 [ 79.042344][ T5153] 12 80 4294944912 [ 79.044195][ T5153] 3 90 4294944912 [ 79.045648][ T5153] 3 80 4294944912 [ 79.047196][ T5153] a1 90 4294944912 [ 79.048543][ T5153] 1e 90 4294944912 [ 79.049987][ T5153] e6 80 4294944912 [ 79.051400][ T5153] 1 90 4294944912 [ 79.052845][ T5153] 0 90 4294944912 [ 79.054901][ T5153] 0 90 4294944912 [ 79.056705][ T5153] 1 90 4294944912 [ 79.058345][ T5153] 2 90 4294944912 [ 79.059847][ T5153] 24 90 4294944912 [ 79.061572][ T5153] 1b 90 4294944912 [ 79.063223][ T5153] ff 90 4294944912 [ 79.065011][ T5153] last result at 4294944913 [ 79.067013][ T5153] last redo_fd_request at 4294945004 [ 79.068872][ T5153] status=80 [ 79.070003][ T5153] fdc_busy=1 [ 79.071158][ T5153] floppy_work.func=floppy_work_workfn [ 79.073082][ T5153] cont=ffffffff8ad02f20 [ 79.074649][ T5153] current_req=0000000000000000 [ 79.076499][ T5153] command_status=-1 [ 79.078213][ T5153] [ 79.079328][ T5153] floppy1: floppy timeout called [ 79.100513][ T5420] floppy1: reschedule timeout lock fdc [ 79.100540][ T5153] [ 79.102474][ T5420] floppy1: setting NEWCHANGE in poll_drive [ 79.105920][ T5153] floppy driver state [ 79.107677][ T5153] ------------------- [ 79.109427][ T5153] now=4294945015 last interrupt=4294944912 diff=103 last called handler=main_command_interrupt [ 79.114390][ T5153] timeout_message=lock fdc [ 79.116416][ T5153] last output bytes: [ 79.118065][ T5153] 0 90 4294944909 [ 79.119634][ T5153] 1 90 4294944909 [ 79.121133][ T5153] 2 90 4294944909 [ 79.122716][ T5153] 12 90 4294944909 [ 79.124752][ T5153] 1b 90 4294944909 [ 79.126485][ T5153] ff 90 4294944909 [ 79.128130][ T5153] 12 80 4294944912 [ 79.129780][ T5153] 3 90 4294944912 [ 79.131369][ T5153] 3 80 4294944912 [ 79.132957][ T5153] a1 90 4294944912 [ 79.134598][ T5153] 1e 90 4294944912 [ 79.136203][ T5153] e6 80 4294944912 [ 79.137795][ T5153] 1 90 4294944912 [ 79.139459][ T5153] 0 90 4294944912 [ 79.141081][ T5153] 0 90 4294944912 [ 79.142772][ T5153] 1 90 4294944912 [ 79.145107][ T5153] 2 90 4294944912 [ 79.146856][ T5153] 24 90 4294944912 [ 79.148594][ T5153] 1b 90 4294944912 [ 79.150023][ T5153] ff 90 4294944912 [ 79.151557][ T5153] last result at 4294944913 [ 79.154011][ T5153] last redo_fd_request at 4294945012 [ 79.156191][ T5153] status=80 [ 79.157435][ T5153] fdc_busy=1 [ 79.158593][ T5153] floppy_work.func=floppy_work_workfn [ 79.160460][ T5153] cont=ffffffff8ad02f20 [ 79.161965][ T5153] current_req=0000000000000000 [ 79.164477][ T5153] command_status=-1 [ 79.165849][ T5153] [ 79.166747][ T5153] floppy1: floppy timeout called [ 79.184035][ T5423] floppy1: reschedule timeout lock fdc [ 79.184298][ T5153] [ 79.186529][ T5423] floppy1: setting NEWCHANGE in poll_drive [ 79.187614][ T5153] floppy driver state [ 79.187621][ T5153] ------------------- [ 79.195189][ T5153] now=4294945024 last interrupt=4294944912 diff=112 last called handler=main_command_interrupt [ 79.199330][ T5153] timeout_message=lock fdc [ 79.201295][ T5153] last output bytes: [ 79.203066][ T5153] 0 90 4294944909 [ 79.204861][ T5153] 1 90 4294944909 [ 79.206390][ T5153] 2 90 4294944909 [ 79.207925][ T5153] 12 90 4294944909 [ 79.209601][ T5153] 1b 90 4294944909 [ 79.211254][ T5153] ff 90 4294944909 [ 79.212956][ T5153] 12 80 4294944912 [ 79.214700][ T5153] 3 90 4294944912 [ 79.216400][ T5153] 3 80 4294944912 [ 79.218063][ T5153] a1 90 4294944912 [ 79.219725][ T5153] 1e 90 4294944912 [ 79.221400][ T5153] e6 80 4294944912 [ 79.223071][ T5153] 1 90 4294944912 [ 79.224728][ T5153] 0 90 4294944912 [ 79.226015][ T5153] 0 90 4294944912 [ 79.227360][ T5153] 1 90 4294944912 [ 79.229011][ T5153] 2 90 4294944912 [ 79.230709][ T5153] 24 90 4294944912 [ 79.232389][ T5153] 1b 90 4294944912 [ 79.234140][ T5153] ff 90 4294944912 [ 79.235737][ T5153] last result at 4294944913 [ 79.237496][ T5153] last redo_fd_request at 4294945021 [ 79.239358][ T5153] status=80 [ 79.240453][ T5153] fdc_busy=1 [ 79.241756][ T5153] floppy_work.func=floppy_work_workfn [ 79.244055][ T5153] cont=ffffffff8ad02f20 [ 79.245701][ T5153] current_req=0000000000000000 [ 79.247393][ T5153] command_status=-1 [ 79.248891][ T5153] [ 79.249937][ T5153] floppy1: floppy timeout called [ 79.268992][ T5426] floppy1: reschedule timeout lock fdc [ 79.269031][ T5153] [ 79.271454][ T5426] floppy1: setting NEWCHANGE in poll_drive [ 79.272428][ T5153] floppy driver state [ 79.272432][ T5153] ------------------- [ 79.278562][ T5153] now=4294945032 last interrupt=4294944912 diff=120 last called handler=main_command_interrupt [ 79.282571][ T5153] timeout_message=lock fdc [ 79.284512][ T5153] last output bytes: [ 79.286146][ T5153] 0 90 4294944909 [ 79.287620][ T5153] 1 90 4294944909 [ 79.289198][ T5153] 2 90 4294944909 [ 79.290583][ T5153] 12 90 4294944909 [ 79.292100][ T5153] 1b 90 4294944909 [ 79.293919][ T5153] ff 90 4294944909 [ 79.295275][ T5153] 12 80 4294944912 [ 79.296737][ T5153] 3 90 4294944912 [ 79.298107][ T5153] 3 80 4294944912 [ 79.299410][ T5153] a1 90 4294944912 [ 79.300746][ T5153] 1e 90 4294944912 [ 79.302079][ T5153] e6 80 4294944912 [ 79.303528][ T5153] 1 90 4294944912 [ 79.305132][ T5153] 0 90 4294944912 [ 79.306763][ T5153] 0 90 4294944912 [ 79.308368][ T5153] 1 90 4294944912 [ 79.309991][ T5153] 2 90 4294944912 [ 79.311609][ T5153] 24 90 4294944912 [ 79.313258][ T5153] 1b 90 4294944912 [ 79.314997][ T5153] ff 90 4294944912 [ 79.316253][ T5153] last result at 4294944913 [ 79.318059][ T5153] last redo_fd_request at 4294945029 [ 79.321107][ T5153] status=80 [ 79.322356][ T5153] fdc_busy=1 [ 79.323794][ T5153] floppy_work.func=floppy_work_workfn [ 79.326147][ T5153] cont=ffffffff8ad02f20 [ 79.327698][ T5153] current_req=0000000000000000 [ 79.329498][ T5153] command_status=-1 [ 79.331164][ T5153] [ 79.332413][ T5153] floppy1: floppy timeout called [ 79.347749][ T5429] floppy1: reschedule timeout lock fdc [ 79.347786][ T5153] [ 79.350324][ T5429] floppy1: setting NEWCHANGE in poll_drive [ 79.351279][ T5153] floppy driver state [ 79.351286][ T5153] ------------------- [ 79.358099][ T5153] now=4294945040 last interrupt=4294944912 diff=128 last called handler=main_command_interrupt [ 79.361786][ T5153] timeout_message=lock fdc [ 79.363667][ T5153] last output bytes: [ 79.365441][ T5153] 0 90 4294944909 [ 79.367060][ T5153] 1 90 4294944909 [ 79.368793][ T5153] 2 90 4294944909 [ 79.370450][ T5153] 12 90 4294944909 [ 79.372111][ T5153] 1b 90 4294944909 [ 79.374065][ T5153] ff 90 4294944909 [ 79.375826][ T5153] 12 80 4294944912 [ 79.377543][ T5153] 3 90 4294944912 [ 79.379217][ T5153] 3 80 4294944912 [ 79.380893][ T5153] a1 90 4294944912 [ 79.382536][ T5153] 1e 90 4294944912 [ 79.383961][ T5153] e6 80 4294944912 [ 79.385361][ T5153] 1 90 4294944912 [ 79.386964][ T5153] 0 90 4294944912 [ 79.388558][ T5153] 0 90 4294944912 [ 79.390176][ T5153] 1 90 4294944912 [ 79.391779][ T5153] 2 90 4294944912 [ 79.393409][ T5153] 24 90 4294944912 [ 79.395164][ T5153] 1b 90 4294944912 [ 79.396745][ T5153] ff 90 4294944912 [ 79.398335][ T5153] last result at 4294944913 [ 79.400346][ T5153] last redo_fd_request at 4294945038 [ 79.402627][ T5153] status=80 [ 79.404161][ T5153] fdc_busy=1 [ 79.405544][ T5153] floppy_work.func=floppy_work_workfn [ 79.407681][ T5153] cont=ffffffff8ad02f20 [ 79.409509][ T5153] current_req=0000000000000000 [ 79.411374][ T5153] command_status=-1 [ 79.412828][ T5153] [ 79.414177][ T5153] floppy1: floppy timeout called [ 79.433270][ T5432] floppy1: reschedule timeout lock fdc [ 79.433729][ T5153] [ 79.435938][ T5432] floppy1: setting NEWCHANGE in poll_drive [ 79.437124][ T5153] floppy driver state [ 79.441177][ T5153] ------------------- [ 79.442917][ T5153] now=4294945048 last interrupt=4294944912 diff=136 last called handler=main_command_interrupt [ 79.447212][ T5153] timeout_message=lock fdc [ 79.449196][ T5153] last output bytes: [ 79.450886][ T5153] 0 90 4294944909 [ 79.452420][ T5153] 1 90 4294944909 [ 79.454263][ T5153] 2 90 4294944909 [ 79.455888][ T5153] 12 90 4294944909 [ 79.457551][ T5153] 1b 90 4294944909 [ 79.459189][ T5153] ff 90 4294944909 [ 79.460696][ T5153] 12 80 4294944912 [ 79.462142][ T5153] 3 90 4294944912 [ 79.463721][ T5153] 3 80 4294944912 [ 79.465310][ T5153] a1 90 4294944912 [ 79.466867][ T5153] 1e 90 4294944912 [ 79.468403][ T5153] e6 80 4294944912 [ 79.469864][ T5153] 1 90 4294944912 [ 79.471150][ T5153] 0 90 4294944912 [ 79.472548][ T5153] 0 90 4294944912 [ 79.474056][ T5153] 1 90 4294944912 [ 79.475694][ T5153] 2 90 4294944912 [ 79.477388][ T5153] 24 90 4294944912 [ 79.479069][ T5153] 1b 90 4294944912 [ 79.480708][ T5153] ff 90 4294944912 [ 79.482359][ T5153] last result at 4294944913 [ 79.485045][ T5153] last redo_fd_request at 4294945046 [ 79.487161][ T5153] status=80 [ 79.488545][ T5153] fdc_busy=1 [ 79.490031][ T5153] floppy_work.func=floppy_work_workfn [ 79.492413][ T5153] cont=ffffffff8ad02f20 [ 79.494436][ T5153] current_req=0000000000000000 [ 79.496631][ T5153] command_status=-1 [ 79.498137][ T5153] [ 79.499078][ T5153] floppy1: floppy timeout called [ 79.514411][ T5435] floppy1: reschedule timeout lock fdc [ 79.514571][ T5153] [ 79.517152][ T5435] floppy1: setting NEWCHANGE in poll_drive [ 79.517990][ T5153] floppy driver state [ 79.517995][ T5153] ------------------- [ 79.517997][ T5153] now=4294945056 last interrupt=4294944912 diff=144 last called handler=main_command_interrupt [ 79.527774][ T5153] timeout_message=lock fdc [ 79.529747][ T5153] last output bytes: [ 79.531209][ T5153] 0 90 4294944909 [ 79.532592][ T5153] 1 90 4294944909 [ 79.534219][ T5153] 2 90 4294944909 [ 79.535607][ T5153] 12 90 4294944909 [ 79.537214][ T5153] 1b 90 4294944909 [ 79.538845][ T5153] ff 90 4294944909 [ 79.540464][ T5153] 12 80 4294944912 [ 79.542099][ T5153] 3 90 4294944912 [ 79.543829][ T5153] 3 80 4294944912 [ 79.545518][ T5153] a1 90 4294944912 [ 79.547118][ T5153] 1e 90 4294944912 [ 79.548713][ T5153] e6 80 4294944912 [ 79.550304][ T5153] 1 90 4294944912 [ 79.551880][ T5153] 0 90 4294944912 [ 79.553580][ T5153] 0 90 4294944912 [ 79.555195][ T5153] 1 90 4294944912 [ 79.556781][ T5153] 2 90 4294944912 [ 79.558343][ T5153] 24 90 4294944912 [ 79.559927][ T5153] 1b 90 4294944912 [ 79.561503][ T5153] ff 90 4294944912 [ 79.562967][ T5153] last result at 4294944913 [ 79.564862][ T5153] last redo_fd_request at 4294945054 [ 79.567109][ T5153] status=80 [ 79.568464][ T5153] fdc_busy=1 [ 79.569799][ T5153] floppy_work.func=floppy_work_workfn [ 79.572009][ T5153] cont=ffffffff8ad02f20 [ 79.573847][ T5153] current_req=0000000000000000 [ 79.575825][ T5153] command_status=-1 [ 79.577478][ T5153] [ 79.578589][ T5153] floppy1: floppy timeout called [ 79.591399][ T5438] floppy1: reschedule timeout lock fdc [ 79.594198][ T5153] [ 79.595094][ T5438] floppy1: setting NEWCHANGE in poll_drive [ 79.595281][ T5153] floppy driver state [ 79.599325][ T5153] ------------------- [ 79.600800][ T5153] now=4294945064 last interrupt=4294944912 diff=152 last called handler=main_command_interrupt [ 79.604865][ T5153] timeout_message=lock fdc [ 79.606840][ T5153] last output bytes: [ 79.608320][ T5153] 0 90 4294944909 [ 79.609706][ T5153] 1 90 4294944909 [ 79.611061][ T5153] 2 90 4294944909 [ 79.612409][ T5153] 12 90 4294944909 [ 79.614231][ T5153] 1b 90 4294944909 [ 79.615737][ T5153] ff 90 4294944909 [ 79.617230][ T5153] 12 80 4294944912 [ 79.618542][ T5153] 3 90 4294944912 [ 79.619997][ T5153] 3 80 4294944912 [ 79.621339][ T5153] a1 90 4294944912 [ 79.622779][ T5153] 1e 90 4294944912 [ 79.624360][ T5153] e6 80 4294944912 [ 79.625746][ T5153] 1 90 4294944912 [ 79.627120][ T5153] 0 90 4294944912 [ 79.628547][ T5153] 0 90 4294944912 [ 79.629928][ T5153] 1 90 4294944912 [ 79.631296][ T5153] 2 90 4294944912 [ 79.632642][ T5153] 24 90 4294944912 [ 79.634213][ T5153] 1b 90 4294944912 [ 79.635765][ T5153] ff 90 4294944912 [ 79.637383][ T5153] last result at 4294944913 [ 79.639314][ T5153] last redo_fd_request at 4294945062 [ 79.641438][ T5153] status=80 [ 79.642789][ T5153] fdc_busy=1 [ 79.644297][ T5153] floppy_work.func=floppy_work_workfn [ 79.646590][ T5153] cont=ffffffff8ad02f20 [ 79.648348][ T5153] current_req=0000000000000000 [ 79.650370][ T5153] command_status=-1 [ 79.651840][ T5153] [ 79.652733][ T5153] floppy1: floppy timeout called [ 79.668815][ T5441] floppy1: reschedule timeout lock fdc [ 79.668845][ T5153] [ 79.671233][ T5441] floppy1: setting NEWCHANGE in poll_drive [ 79.672264][ T5153] floppy driver state [ 79.677132][ T5153] ------------------- [ 79.678911][ T5153] now=4294945072 last interrupt=4294944912 diff=160 last called handler=main_command_interrupt [ 79.683088][ T5153] timeout_message=lock fdc [ 79.685195][ T5153] last output bytes: [ 79.686757][ T5153] 0 90 4294944909 [ 79.688243][ T5153] 1 90 4294944909 [ 79.689822][ T5153] 2 90 4294944909 [ 79.691408][ T5153] 12 90 4294944909 [ 79.693055][ T5153] 1b 90 4294944909 [ 79.694715][ T5153] ff 90 4294944909 [ 79.696130][ T5153] 12 80 4294944912 [ 79.697808][ T5153] 3 90 4294944912 [ 79.699531][ T5153] 3 80 4294944912 [ 79.701000][ T5153] a1 90 4294944912 [ 79.702598][ T5153] 1e 90 4294944912 [ 79.704213][ T5153] e6 80 4294944912 [ 79.705733][ T5153] 1 90 4294944912 [ 79.707204][ T5153] 0 90 4294944912 [ 79.708686][ T5153] 0 90 4294944912 [ 79.710321][ T5153] 1 90 4294944912 [ 79.711940][ T5153] 2 90 4294944912 [ 79.713656][ T5153] 24 90 4294944912 [ 79.715240][ T5153] 1b 90 4294944912 [ 79.716875][ T5153] ff 90 4294944912 [ 79.718497][ T5153] last result at 4294944913 [ 79.720486][ T5153] last redo_fd_request at 4294945070 [ 79.722660][ T5153] status=80 [ 79.724162][ T5153] fdc_busy=1 [ 79.725662][ T5153] floppy_work.func=floppy_work_workfn [ 79.727932][ T5153] cont=ffffffff8ad02f20 [ 79.729714][ T5153] current_req=0000000000000000 [ 79.731733][ T5153] command_status=-1 [ 79.733401][ T5153] [ 79.734579][ T5153] floppy1: floppy timeout called [ 79.752797][ T5444] floppy1: reschedule timeout lock fdc [ 79.752841][ T10] [ 79.755686][ T5444] floppy1: setting NEWCHANGE in poll_drive [ 79.756326][ T10] floppy driver state [ 79.759511][ T10] ------------------- [ 79.761095][ T10] now=4294945080 last interrupt=4294944912 diff=168 last called handler=main_command_interrupt [ 79.765893][ T10] timeout_message=lock fdc [ 79.767642][ T10] last output bytes: [ 79.769083][ T10] 0 90 4294944909 [ 79.770419][ T10] 1 90 4294944909 [ 79.771759][ T10] 2 90 4294944909 [ 79.773154][ T10] 12 90 4294944909 [ 79.774795][ T10] 1b 90 4294944909 [ 79.776129][ T10] ff 90 4294944909 [ 79.777548][ T10] 12 80 4294944912 [ 79.778999][ T10] 3 90 4294944912 [ 79.780342][ T10] 3 80 4294944912 [ 79.781732][ T10] a1 90 4294944912 [ 79.783274][ T10] 1e 90 4294944912 [ 79.785168][ T10] e6 80 4294944912 [ 79.786671][ T10] 1 90 4294944912 [ 79.788005][ T10] 0 90 4294944912 [ 79.789352][ T10] 0 90 4294944912 [ 79.790666][ T10] 1 90 4294944912 [ 79.792084][ T10] 2 90 4294944912 [ 79.793826][ T10] 24 90 4294944912 [ 79.795235][ T10] 1b 90 4294944912 [ 79.796611][ T10] ff 90 4294944912 [ 79.798181][ T10] last result at 4294944913 [ 79.799989][ T10] last redo_fd_request at 4294945078 [ 79.802175][ T10] status=80 [ 79.803604][ T10] fdc_busy=1 [ 79.805030][ T10] floppy_work.func=floppy_work_workfn [ 79.807393][ T10] cont=ffffffff8ad02f20 [ 79.809231][ T10] current_req=0000000000000000 [ 79.811238][ T10] command_status=-1 [ 79.812917][ T10] [ 79.814120][ T10] floppy1: floppy timeout called [ 79.830648][ T5447] floppy1: reschedule timeout lock fdc [ 79.830790][ T10] [ 79.832863][ T5447] floppy1: setting NEWCHANGE in poll_drive [ 79.834462][ T10] floppy driver state [ 79.838005][ T10] ------------------- [ 79.839710][ T10] now=4294945088 last interrupt=4294944912 diff=176 last called handler=main_command_interrupt [ 79.844082][ T10] timeout_message=lock fdc [ 79.845994][ T10] last output bytes: [ 79.847741][ T10] 0 90 4294944909 [ 79.849430][ T10] 1 90 4294944909 [ 79.850994][ T10] 2 90 4294944909 [ 79.852522][ T10] 12 90 4294944909 [ 79.855071][ T10] 1b 90 4294944909 [ 79.856697][ T10] ff 90 4294944909 [ 79.858359][ T10] 12 80 4294944912 [ 79.859843][ T10] 3 90 4294944912 [ 79.861570][ T10] 3 80 4294944912 [ 79.863232][ T10] a1 90 4294944912 [ 79.864989][ T10] 1e 90 4294944912 [ 79.866669][ T10] e6 80 4294944912 [ 79.868340][ T10] 1 90 4294944912 [ 79.870039][ T10] 0 90 4294944912 [ 79.871687][ T10] 0 90 4294944912 [ 79.873289][ T10] 1 90 4294944912 [ 79.874936][ T10] 2 90 4294944912 [ 79.876546][ T10] 24 90 4294944912 [ 79.878083][ T10] 1b 90 4294944912 [ 79.879562][ T10] ff 90 4294944912 [ 79.881238][ T10] last result at 4294944913 [ 79.884560][ T10] last redo_fd_request at 4294945086 [ 79.886899][ T10] status=80 [ 79.888308][ T10] fdc_busy=1 [ 79.889711][ T10] floppy_work.func=floppy_work_workfn [ 79.891663][ T10] cont=ffffffff8ad02f20 [ 79.893215][ T10] current_req=0000000000000000 [ 79.895190][ T10] command_status=-1 [ 79.896753][ T10] [ 79.897726][ T10] floppy1: floppy timeout called [ 79.922481][ T5450] floppy1: reschedule timeout lock fdc [ 79.922614][ T10] [ 79.925871][ T5450] floppy1: setting NEWCHANGE in poll_drive [ 79.926344][ T10] floppy driver state [ 79.930011][ T10] ------------------- [ 79.931750][ T10] now=4294945097 last interrupt=4294944912 diff=185 last called handler=main_command_interrupt [ 79.936003][ T10] timeout_message=lock fdc [ 79.937809][ T10] last output bytes: [ 79.939359][ T10] 0 90 4294944909 [ 79.940869][ T10] 1 90 4294944909 [ 79.942311][ T10] 2 90 4294944909 [ 79.944168][ T10] 12 90 4294944909 [ 79.945673][ T10] 1b 90 4294944909 [ 79.947168][ T10] ff 90 4294944909 [ 79.948631][ T10] 12 80 4294944912 [ 79.950157][ T10] 3 90 4294944912 [ 79.951677][ T10] 3 80 4294944912 [ 79.953207][ T10] a1 90 4294944912 [ 79.954926][ T10] 1e 90 4294944912 [ 79.956390][ T10] e6 80 4294944912 [ 79.957844][ T10] 1 90 4294944912 [ 79.959205][ T10] 0 90 4294944912 [ 79.960533][ T10] 0 90 4294944912 [ 79.961895][ T10] 1 90 4294944912 [ 79.963231][ T10] 2 90 4294944912 [ 79.964664][ T10] 24 90 4294944912 [ 79.966009][ T10] 1b 90 4294944912 [ 79.967462][ T10] ff 90 4294944912 [ 79.968924][ T10] last result at 4294944913 [ 79.970696][ T10] last redo_fd_request at 4294945094 [ 79.972686][ T10] status=80 [ 79.974258][ T10] fdc_busy=1 [ 79.975524][ T10] floppy_work.func=floppy_work_workfn [ 79.977598][ T10] cont=ffffffff8ad02f20 [ 79.979174][ T10] current_req=0000000000000000 [ 79.980946][ T10] command_status=-1 [ 79.982371][ T10] [ 79.983307][ T10] floppy1: floppy timeout called [ 79.996968][ T5453] floppy1: reschedule timeout lock fdc [ 79.997046][ T10] [ 79.999046][ T5453] floppy1: setting NEWCHANGE in poll_drive [ 79.999854][ T10] floppy driver state [ 79.999858][ T10] ------------------- [ 80.005625][ T10] now=4294945105 last interrupt=4294944912 diff=193 last called handler=main_command_interrupt [ 80.009644][ T10] timeout_message=lock fdc [ 80.011397][ T10] last output bytes: [ 80.012978][ T10] 0 90 4294944909 [ 80.014740][ T10] 1 90 4294944909 [ 80.016378][ T10] 2 90 4294944909 [ 80.017982][ T10] 12 90 4294944909 [ 80.019446][ T10] 1b 90 4294944909 [ 80.020866][ T10] ff 90 4294944909 [ 80.022328][ T10] 12 80 4294944912 [ 80.023972][ T10] 3 90 4294944912 [ 80.025393][ T10] 3 80 4294944912 [ 80.026798][ T10] a1 90 4294944912 [ 80.028201][ T10] 1e 90 4294944912 [ 80.029656][ T10] e6 80 4294944912 [ 80.031146][ T10] 1 90 4294944912 [ 80.032654][ T10] 0 90 4294944912 [ 80.034536][ T10] 0 90 4294944912 [ 80.036058][ T10] 1 90 4294944912 [ 80.037644][ T10] 2 90 4294944912 [ 80.039074][ T10] 24 90 4294944912 [ 80.040493][ T10] 1b 90 4294944912 [ 80.041844][ T10] ff 90 4294944912 [ 80.043330][ T10] last result at 4294944913 [ 80.045305][ T10] last redo_fd_request at 4294945103 [ 80.047527][ T10] status=80 [ 80.048838][ T10] fdc_busy=1 [ 80.050229][ T10] floppy_work.func=floppy_work_workfn [ 80.052265][ T10] cont=ffffffff8ad02f20 [ 80.054019][ T10] current_req=0000000000000000 [ 80.055957][ T10] command_status=-1 [ 80.057524][ T10] [ 80.058419][ T10] floppy1: floppy timeout called [ 80.073905][ T5456] floppy1: reschedule timeout lock fdc [ 80.074348][ T5219] [ 80.075890][ T5456] floppy1: setting NEWCHANGE in poll_drive [ 80.076903][ T5219] floppy driver state [ 80.080392][ T5219] ------------------- [ 80.082681][ T5219] now=4294945112 last interrupt=4294944912 diff=200 last called handler=main_command_interrupt [ 80.087038][ T5219] timeout_message=lock fdc [ 80.089308][ T5219] last output bytes: [ 80.091148][ T5219] 0 90 4294944909 [ 80.093377][ T5219] 1 90 4294944909 [ 80.095027][ T5219] 2 90 4294944909 [ 80.096563][ T5219] 12 90 4294944909 [ 80.098481][ T5219] 1b 90 4294944909 [ 80.101796][ T5219] ff 90 4294944909 [ 80.103255][ T5219] 12 80 4294944912 [ 80.104967][ T5219] 3 90 4294944912 [ 80.106296][ T5219] 3 80 4294944912 [ 80.107932][ T5219] a1 90 4294944912 [ 80.110249][ T5219] 1e 90 4294944912 [ 80.112474][ T5219] e6 80 4294944912 [ 80.114170][ T5219] 1 90 4294944912 [ 80.115536][ T5219] 0 90 4294944912 [ 80.116901][ T5219] 0 90 4294944912 [ 80.118229][ T5219] 1 90 4294944912 [ 80.119559][ T5219] 2 90 4294944912 [ 80.120935][ T5219] 24 90 4294944912 [ 80.122408][ T5219] 1b 90 4294944912 [ 80.125123][ T5219] ff 90 4294944912 [ 80.127013][ T5219] last result at 4294944913 [ 80.128893][ T5219] last redo_fd_request at 4294945110 [ 80.131174][ T5219] status=80 [ 80.132332][ T5219] fdc_busy=1 [ 80.134092][ T5219] floppy_work.func=floppy_work_workfn [ 80.136422][ T5219] cont=ffffffff8ad02f20 [ 80.138668][ T5219] current_req=0000000000000000 [ 80.140897][ T5219] command_status=-1 [ 80.143064][ T5219] [ 80.144651][ T5219] floppy1: floppy timeout called [ 80.157280][ T5459] floppy1: reschedule timeout lock fdc [ 80.160247][ T5219] [ 80.160275][ T5459] floppy1: setting NEWCHANGE in poll_drive [ 80.161447][ T5219] floppy driver state [ 80.161452][ T5219] ------------------- [ 80.161455][ T5219] now=4294945120 last interrupt=4294944912 diff=208 last called handler=main_command_interrupt [ 80.161471][ T5219] timeout_message=lock fdc [ 80.161475][ T5219] last output bytes: [ 80.161477][ T5219] 0 90 4294944909 [ 80.161483][ T5219] 1 90 4294944909 [ 80.161487][ T5219] 2 90 4294944909 [ 80.161492][ T5219] 12 90 4294944909 [ 80.182573][ T5219] 1b 90 4294944909 [ 80.184518][ T5219] ff 90 4294944909 [ 80.186183][ T5219] 12 80 4294944912 [ 80.187847][ T5219] 3 90 4294944912 [ 80.189823][ T5219] 3 80 4294944912 [ 80.191668][ T5219] a1 90 4294944912 [ 80.194062][ T5219] 1e 90 4294944912 [ 80.196179][ T5219] e6 80 4294944912 [ 80.197791][ T5219] 1 90 4294944912 [ 80.199416][ T5219] 0 90 4294944912 [ 80.201112][ T5219] 0 90 4294944912 [ 80.202622][ T5219] 1 90 4294944912 [ 80.204975][ T5219] 2 90 4294944912 [ 80.206600][ T5219] 24 90 4294944912 [ 80.208136][ T5219] 1b 90 4294944912 [ 80.209509][ T5219] ff 90 4294944912 [ 80.210842][ T5219] last result at 4294944913 [ 80.212479][ T5219] last redo_fd_request at 4294945119 [ 80.214797][ T5219] status=80 [ 80.216160][ T5219] fdc_busy=1 [ 80.217393][ T5219] floppy_work.func=floppy_work_workfn [ 80.219321][ T5219] cont=ffffffff8ad02f20 [ 80.220811][ T5219] current_req=0000000000000000 [ 80.222485][ T5219] command_status=-1 [ 80.224261][ T5219] [ 80.225596][ T5219] floppy1: floppy timeout called [ 80.250904][ T5462] floppy1: reschedule timeout lock fdc [ 80.250938][ T5219] [ 80.253253][ T5462] floppy1: setting NEWCHANGE in poll_drive [ 80.256789][ T5219] floppy driver state [ 80.258401][ T5219] ------------------- [ 80.260009][ T5219] now=4294945130 last interrupt=4294944912 diff=218 last called handler=main_command_interrupt [ 80.264158][ T5219] timeout_message=lock fdc [ 80.265973][ T5219] last output bytes: [ 80.267533][ T5219] 0 90 4294944909 [ 80.268944][ T5219] 1 90 4294944909 [ 80.270270][ T5219] 2 90 4294944909 [ 80.271574][ T5219] 12 90 4294944909 [ 80.272881][ T5219] 1b 90 4294944909 [ 80.274365][ T5219] ff 90 4294944909 [ 80.275810][ T5219] 12 80 4294944912 [ 80.277408][ T5219] 3 90 4294944912 [ 80.279024][ T5219] 3 80 4294944912 [ 80.280639][ T5219] a1 90 4294944912 [ 80.282233][ T5219] 1e 90 4294944912 [ 80.284259][ T5219] e6 80 4294944912 [ 80.285811][ T5219] 1 90 4294944912 [ 80.287219][ T5219] 0 90 4294944912 [ 80.288656][ T5219] 0 90 4294944912 [ 80.290059][ T5219] 1 90 4294944912 [ 80.291438][ T5219] 2 90 4294944912 [ 80.292831][ T5219] 24 90 4294944912 [ 80.294841][ T5219] 1b 90 4294944912 [ 80.296404][ T5219] ff 90 4294944912 [ 80.297839][ T5219] last result at 4294944913 [ 80.299593][ T5219] last redo_fd_request at 4294945127 [ 80.301567][ T5219] status=80 [ 80.302722][ T5219] fdc_busy=1 [ 80.304016][ T5219] floppy_work.func=floppy_work_workfn [ 80.306054][ T40] audit: type=1400 audit(1685279806.920:212): avc: denied { rename } for pid=4603 comm="syslogd" name="messages" dev="tmpfs" ino=3 scontext=system_u:system_r:syslogd_t tcontext=system_u:object_r:tmpfs_t tclass=file permissive=1 [ 80.306981][ T5219] cont=ffffffff8ad02f20 [ 80.314092][ T40] audit: type=1400 audit(1685279806.920:213): avc: denied { unlink } for pid=4603 comm="syslogd" name="messages.0" dev="tmpfs" ino=2 scontext=system_u:system_r:syslogd_t tcontext=system_u:object_r:tmpfs_t tclass=file permissive=1 [ 80.315581][ T5219] current_req=0000000000000000 [ 80.323352][ T40] audit: type=1400 audit(1685279806.920:214): avc: denied { create } for pid=4603 comm="syslogd" name="messages" scontext=system_u:system_r:syslogd_t tcontext=system_u:object_r:tmpfs_t tclass=file permissive=1 [ 80.332284][ T5219] command_status=-1 [ 80.333748][ T5219] [ 80.334723][ T5219] floppy1: floppy timeout called [ 80.347742][ T5465] floppy1: reschedule timeout lock fdc [ 80.347806][ T5219] [ 80.349953][ T5465] floppy1: setting NEWCHANGE in poll_drive [ 80.350761][ T5219] floppy driver state [ 80.350767][ T5219] ------------------- [ 80.356926][ T5219] now=4294945140 last interrupt=4294944912 diff=228 last called handler=main_command_interrupt [ 80.361481][ T5219] timeout_message=lock fdc [ 80.363653][ T5219] last output bytes: [ 80.365458][ T5219] 0 90 4294944909 [ 80.367104][ T5219] 1 90 4294944909 [ 80.368788][ T5219] 2 90 4294944909 [ 80.370448][ T5219] 12 90 4294944909 [ 80.372108][ T5219] 1b 90 4294944909 [ 80.373866][ T5219] ff 90 4294944909 [ 80.375513][ T5219] 12 80 4294944912 [ 80.377143][ T5219] 3 90 4294944912 [ 80.378611][ T5219] 3 80 4294944912 [ 80.379941][ T5219] a1 90 4294944912 [ 80.381469][ T5219] 1e 90 4294944912 [ 80.383048][ T5219] e6 80 4294944912 [ 80.384652][ T5219] 1 90 4294944912 [ 80.386087][ T5219] 0 90 4294944912 [ 80.387529][ T5219] 0 90 4294944912 [ 80.389197][ T5219] 1 90 4294944912 [ 80.390828][ T5219] 2 90 4294944912 [ 80.392443][ T5219] 24 90 4294944912 [ 80.394261][ T5219] 1b 90 4294944912 [ 80.395934][ T5219] ff 90 4294944912 [ 80.397666][ T5219] last result at 4294944913 [ 80.399656][ T5219] last redo_fd_request at 4294945138 [ 80.401936][ T5219] status=80 [ 80.403292][ T5219] fdc_busy=1 [ 80.404725][ T5219] floppy_work.func=floppy_work_workfn [ 80.407002][ T5219] cont=ffffffff8ad02f20 [ 80.408622][ T5219] current_req=0000000000000000 [ 80.410541][ T5219] command_status=-1 [ 80.412052][ T5219] [ 80.413154][ T5219] floppy1: floppy timeout called [ 80.427606][ T5468] floppy1: reschedule timeout lock fdc [ 80.429892][ T5468] floppy1: setting NEWCHANGE in poll_drive [ 80.430310][ T5219] [ 80.433189][ T5219] floppy driver state [ 80.434772][ T5219] ------------------- [ 80.436410][ T5219] now=4294945148 last interrupt=4294944912 diff=236 last called handler=main_command_interrupt [ 80.440143][ T5219] timeout_message=lock fdc [ 80.441872][ T5219] last output bytes: [ 80.443514][ T5219] 0 90 4294944909 [ 80.444942][ T5219] 1 90 4294944909 [ 80.446259][ T5219] 2 90 4294944909 [ 80.447473][ T5219] 12 90 4294944909 [ 80.448860][ T5219] 1b 90 4294944909 [ 80.450206][ T5219] ff 90 4294944909 [ 80.451589][ T5219] 12 80 4294944912 [ 80.453225][ T5219] 3 90 4294944912 [ 80.454991][ T5219] 3 80 4294944912 [ 80.456269][ T5219] a1 90 4294944912 [ 80.457630][ T5219] 1e 90 4294944912 [ 80.459211][ T5219] e6 80 4294944912 [ 80.460562][ T5219] 1 90 4294944912 [ 80.461939][ T5219] 0 90 4294944912 [ 80.463283][ T5219] 0 90 4294944912 [ 80.464877][ T5219] 1 90 4294944912 [ 80.466159][ T5219] 2 90 4294944912 [ 80.467472][ T5219] 24 90 4294944912 [ 80.468806][ T5219] 1b 90 4294944912 [ 80.470123][ T5219] ff 90 4294944912 [ 80.471468][ T5219] last result at 4294944913 [ 80.473059][ T5219] last redo_fd_request at 4294945146 [ 80.474957][ T5219] status=80 [ 80.476116][ T5219] fdc_busy=1 [ 80.477279][ T5219] floppy_work.func=floppy_work_workfn [ 80.480432][ T5219] cont=ffffffff8ad02f20 [ 80.482155][ T5219] current_req=0000000000000000 [ 80.483995][ T5219] command_status=-1 [ 80.485347][ T5219] [ 80.486239][ T5219] floppy1: floppy timeout called [ 80.498322][ T5471] floppy1: reschedule timeout lock fdc [ 80.498354][ T5219] [ 80.500540][ T5471] floppy1: setting NEWCHANGE in poll_drive [ 80.501179][ T5219] floppy driver state [ 80.501183][ T5219] ------------------- [ 80.501186][ T5219] now=4294945154 last interrupt=4294944912 diff=242 last called handler=main_command_interrupt [ 80.509353][ T5219] timeout_message=lock fdc [ 80.510770][ T5219] last output bytes: [ 80.512017][ T5219] 0 90 4294944909 [ 80.513277][ T5219] 1 90 4294944909 [ 80.515260][ T5219] 2 90 4294944909 [ 80.516780][ T5219] 12 90 4294944909 [ 80.518291][ T5219] 1b 90 4294944909 [ 80.519772][ T5219] ff 90 4294944909 [ 80.521241][ T5219] 12 80 4294944912 [ 80.522694][ T5219] 3 90 4294944912 [ 80.524310][ T5219] 3 80 4294944912 [ 80.525798][ T5219] a1 90 4294944912 [ 80.527301][ T5219] 1e 90 4294944912 [ 80.528777][ T5219] e6 80 4294944912 [ 80.530013][ T5219] 1 90 4294944912 [ 80.531462][ T5219] 0 90 4294944912 [ 80.533025][ T5219] 0 90 4294944912 [ 80.534339][ T5219] 1 90 4294944912 [ 80.535413][ T5219] 2 90 4294944912 [ 80.536488][ T5219] 24 90 4294944912 [ 80.537689][ T5219] 1b 90 4294944912 [ 80.538905][ T5219] ff 90 4294944912 [ 80.540089][ T5219] last result at 4294944913 [ 80.541538][ T5219] last redo_fd_request at 4294945153 [ 80.543247][ T5219] status=80 [ 80.544457][ T5219] fdc_busy=1 [ 80.545586][ T5219] floppy_work.func=floppy_work_workfn [ 80.547382][ T5219] cont=ffffffff8ad02f20 [ 80.549495][ T5219] current_req=0000000000000000 [ 80.551209][ T5219] command_status=-1 [ 80.552794][ T5219] [ 80.553937][ T5219] floppy1: floppy timeout called [ 80.570394][ T5474] floppy1: reschedule timeout lock fdc [ 80.570430][ T5153] [ 80.572732][ T5474] floppy1: setting NEWCHANGE in poll_drive [ 80.573747][ T5153] floppy driver state [ 80.573752][ T5153] ------------------- [ 80.573754][ T5153] now=4294945162 last interrupt=4294944912 diff=250 last called handler=main_command_interrupt [ 80.573770][ T5153] timeout_message=lock fdc [ 80.573774][ T5153] last output bytes: [ 80.573777][ T5153] 0 90 4294944909 [ 80.573782][ T5153] 1 90 4294944909 [ 80.573786][ T5153] 2 90 4294944909 [ 80.573791][ T5153] 12 90 4294944909 [ 80.573796][ T5153] 1b 90 4294944909 [ 80.573800][ T5153] ff 90 4294944909 [ 80.594679][ T5153] 12 80 4294944912 [ 80.596112][ T5153] 3 90 4294944912 [ 80.597572][ T5153] 3 80 4294944912 [ 80.599129][ T5153] a1 90 4294944912 [ 80.600780][ T5153] 1e 90 4294944912 [ 80.602476][ T5153] e6 80 4294944912 [ 80.604378][ T5153] 1 90 4294944912 [ 80.606113][ T5153] 0 90 4294944912 [ 80.607809][ T5153] 0 90 4294944912 [ 80.609464][ T5153] 1 90 4294944912 [ 80.611154][ T5153] 2 90 4294944912 [ 80.612860][ T5153] 24 90 4294944912 [ 80.615101][ T5153] 1b 90 4294944912 [ 80.616595][ T5153] ff 90 4294944912 [ 80.618032][ T5153] last result at 4294944913 [ 80.619618][ T5153] last redo_fd_request at 4294945160 [ 80.621522][ T5153] status=80 [ 80.622671][ T5153] fdc_busy=1 [ 80.623886][ T5153] floppy_work.func=floppy_work_workfn [ 80.625763][ T5153] cont=ffffffff8ad02f20 [ 80.627247][ T5153] current_req=0000000000000000 [ 80.629028][ T5153] command_status=-1 [ 80.630457][ T5153] [ 80.631385][ T5153] floppy1: floppy timeout called [ 80.643039][ T5477] floppy1: reschedule timeout lock fdc [ 80.643639][ T5153] [ 80.645556][ T5477] floppy1: setting NEWCHANGE in poll_drive [ 80.646344][ T5153] floppy driver state [ 80.646349][ T5153] ------------------- [ 80.651664][ T5153] now=4294945169 last interrupt=4294944912 diff=257 last called handler=main_command_interrupt [ 80.656037][ T5153] timeout_message=lock fdc [ 80.657623][ T5153] last output bytes: [ 80.659087][ T5153] 0 90 4294944909 [ 80.660578][ T5153] 1 90 4294944909 [ 80.661906][ T5153] 2 90 4294944909 [ 80.663221][ T5153] 12 90 4294944909 [ 80.664604][ T5153] 1b 90 4294944909 [ 80.665924][ T5153] ff 90 4294944909 [ 80.667278][ T5153] 12 80 4294944912 [ 80.668596][ T5153] 3 90 4294944912 [ 80.669942][ T5153] 3 80 4294944912 [ 80.671400][ T5153] a1 90 4294944912 [ 80.672966][ T5153] 1e 90 4294944912 [ 80.674689][ T5153] e6 80 4294944912 [ 80.676275][ T5153] 1 90 4294944912 [ 80.677701][ T5153] 0 90 4294944912 [ 80.679010][ T5153] 0 90 4294944912 [ 80.680643][ T5153] 1 90 4294944912 [ 80.682297][ T5153] 2 90 4294944912 [ 80.684059][ T5153] 24 90 4294944912 [ 80.685535][ T5153] 1b 90 4294944912 [ 80.687188][ T5153] ff 90 4294944912 [ 80.688616][ T5153] last result at 4294944913 [ 80.690273][ T5153] last redo_fd_request at 4294945168 [ 80.692492][ T5153] status=80 [ 80.694542][ T5153] fdc_busy=1 [ 80.696034][ T5153] floppy_work.func=floppy_work_workfn [ 80.698376][ T5153] cont=ffffffff8ad02f20 [ 80.700179][ T5153] current_req=0000000000000000 [ 80.702043][ T5153] command_status=-1 [ 80.703980][ T5153] [ 80.704886][ T5153] floppy1: floppy timeout called [ 80.721519][ T5480] floppy1: reschedule timeout lock fdc [ 80.723608][ T5153] [ 80.724331][ T5480] floppy1: setting NEWCHANGE in poll_drive [ 80.724948][ T5153] floppy driver state [ 80.724954][ T5153] ------------------- [ 80.730623][ T5153] now=4294945177 last interrupt=4294944912 diff=265 last called handler=main_command_interrupt [ 80.734571][ T5153] timeout_message=lock fdc [ 80.736373][ T5153] last output bytes: [ 80.737827][ T5153] 0 90 4294944909 [ 80.739154][ T5153] 1 90 4294944909 [ 80.740490][ T5153] 2 90 4294944909 [ 80.741892][ T5153] 12 90 4294944909 [ 80.743413][ T5153] 1b 90 4294944909 [ 80.745242][ T5153] ff 90 4294944909 [ 80.746974][ T5153] 12 80 4294944912 [ 80.748527][ T5153] 3 90 4294944912 [ 80.749891][ T5153] 3 80 4294944912 [ 80.751517][ T5153] a1 90 4294944912 [ 80.753123][ T5153] 1e 90 4294944912 [ 80.754804][ T5153] e6 80 4294944912 [ 80.756157][ T5153] 1 90 4294944912 [ 80.757547][ T5153] 0 90 4294944912 [ 80.759218][ T5153] 0 90 4294944912 [ 80.760788][ T5153] 1 90 4294944912 [ 80.762494][ T5153] 2 90 4294944912 [ 80.764282][ T5153] 24 90 4294944912 [ 80.765972][ T5153] 1b 90 4294944912 [ 80.767671][ T5153] ff 90 4294944912 [ 80.769133][ T5153] last result at 4294944913 [ 80.770878][ T5153] last redo_fd_request at 4294945175 [ 80.774007][ T5153] status=80 [ 80.775472][ T5153] fdc_busy=1 [ 80.776987][ T5153] floppy_work.func=floppy_work_workfn [ 80.779311][ T5153] cont=ffffffff8ad02f20 [ 80.781104][ T5153] current_req=0000000000000000 [ 80.783170][ T5153] command_status=-1 [ 80.785316][ T5153] [ 80.786400][ T5153] floppy1: floppy timeout called [ 80.798091][ T5483] floppy1: reschedule timeout lock fdc [ 80.800253][ T5483] floppy1: setting NEWCHANGE in poll_drive [ 80.802553][ T5153] [ 80.803608][ T5153] floppy driver state [ 80.803609][ T5134] Bluetooth: hci0: command 0x041b tx timeout [ 80.805185][ T5153] ------------------- [ 80.805192][ T5153] now=4294945185 last interrupt=4294944912 diff=273 last called handler=main_command_interrupt [ 80.805214][ T5153] timeout_message=lock fdc [ 80.805219][ T5153] last output bytes: [ 80.805223][ T5153] 0 90 4294944909 [ 80.818621][ T5153] 1 90 4294944909 [ 80.820167][ T5153] 2 90 4294944909 [ 80.821768][ T5153] 12 90 4294944909 [ 80.823315][ T5153] 1b 90 4294944909 [ 80.825016][ T5153] ff 90 4294944909 [ 80.826341][ T5153] 12 80 4294944912 [ 80.827665][ T5153] 3 90 4294944912 [ 80.828993][ T5153] 3 80 4294944912 [ 80.830319][ T5153] a1 90 4294944912 [ 80.831597][ T5153] 1e 90 4294944912 [ 80.832930][ T5153] e6 80 4294944912 [ 80.834321][ T5153] 1 90 4294944912 [ 80.835725][ T5153] 0 90 4294944912 [ 80.837175][ T5153] 0 90 4294944912 [ 80.838565][ T5153] 1 90 4294944912 [ 80.840020][ T5153] 2 90 4294944912 [ 80.841512][ T5153] 24 90 4294944912 [ 80.843275][ T5153] 1b 90 4294944912 [ 80.845057][ T5153] ff 90 4294944912 [ 80.846736][ T5153] last result at 4294944913 [ 80.848785][ T5153] last redo_fd_request at 4294945183 [ 80.851148][ T5153] status=80 [ 80.852326][ T5153] fdc_busy=1 [ 80.853546][ T5153] floppy_work.func=floppy_work_workfn [ 80.855553][ T5153] cont=ffffffff8ad02f20 [ 80.857202][ T5153] current_req=0000000000000000 [ 80.858903][ T5153] command_status=-1 [ 80.860367][ T5153] [ 80.861364][ T5153] floppy1: floppy timeout called [ 80.878490][ T5486] floppy1: reschedule timeout lock fdc [ 80.880681][ T5486] floppy1: setting NEWCHANGE in poll_drive [ 80.883029][ T5153] [ 80.884103][ T5153] floppy driver state [ 80.885702][ T5153] ------------------- [ 80.887224][ T5153] now=4294945193 last interrupt=4294944912 diff=281 last called handler=main_command_interrupt [ 80.891024][ T5153] timeout_message=lock fdc [ 80.892712][ T5153] last output bytes: [ 80.894856][ T5153] 0 90 4294944909 [ 80.896363][ T5153] 1 90 4294944909 [ 80.897760][ T5153] 2 90 4294944909 [ 80.899194][ T5153] 12 90 4294944909 [ 80.900695][ T5153] 1b 90 4294944909 [ 80.902275][ T5153] ff 90 4294944909 [ 80.904272][ T5153] 12 80 4294944912 [ 80.906010][ T5153] 3 90 4294944912 [ 80.907555][ T5153] 3 80 4294944912 [ 80.908880][ T5153] a1 90 4294944912 [ 80.910185][ T5153] 1e 90 4294944912 [ 80.911714][ T5153] e6 80 4294944912 [ 80.913032][ T5153] 1 90 4294944912 [ 80.915011][ T5153] 0 90 4294944912 [ 80.916332][ T5153] 0 90 4294944912 [ 80.917806][ T5153] 1 90 4294944912 [ 80.919161][ T5153] 2 90 4294944912 [ 80.920566][ T5153] 24 90 4294944912 [ 80.922025][ T5153] 1b 90 4294944912 [ 80.923485][ T5153] ff 90 4294944912 [ 80.925087][ T5153] last result at 4294944913 [ 80.927072][ T5153] last redo_fd_request at 4294945190 [ 80.929028][ T5153] status=80 [ 80.930143][ T5153] fdc_busy=1 [ 80.931341][ T5153] floppy_work.func=floppy_work_workfn [ 80.933212][ T5153] cont=ffffffff8ad02f20 [ 80.935352][ T5153] current_req=0000000000000000 [ 80.937088][ T5153] command_status=-1 [ 80.938427][ T5153] [ 80.939298][ T5153] floppy1: floppy timeout called [ 80.952990][ T5489] floppy1: reschedule timeout lock fdc [ 80.953031][ T5153] [ 80.955301][ T5489] floppy1: setting NEWCHANGE in poll_drive [ 80.955934][ T5153] floppy driver state [ 80.959400][ T5153] ------------------- [ 80.960801][ T5153] now=4294945200 last interrupt=4294944912 diff=288 last called handler=main_command_interrupt [ 80.964968][ T5153] timeout_message=lock fdc [ 80.966706][ T5153] last output bytes: [ 80.968073][ T5153] 0 90 4294944909 [ 80.969399][ T5153] 1 90 4294944909 [ 80.970907][ T5153] 2 90 4294944909 [ 80.972266][ T5153] 12 90 4294944909 [ 80.973667][ T5153] 1b 90 4294944909 [ 80.974934][ T5153] ff 90 4294944909 [ 80.976319][ T5153] 12 80 4294944912 [ 80.977926][ T5153] 3 90 4294944912 [ 80.979612][ T5153] 3 80 4294944912 [ 80.981018][ T5153] a1 90 4294944912 [ 80.982325][ T5153] 1e 90 4294944912 [ 80.983714][ T5153] e6 80 4294944912 [ 80.985041][ T5153] 1 90 4294944912 [ 80.986498][ T5153] 0 90 4294944912 [ 80.987980][ T5153] 0 90 4294944912 [ 80.989497][ T5153] 1 90 4294944912 [ 80.991092][ T5153] 2 90 4294944912 [ 80.992420][ T5153] 24 90 4294944912 [ 80.993821][ T5153] 1b 90 4294944912 [ 80.995163][ T5153] ff 90 4294944912 [ 80.996465][ T5153] last result at 4294944913 [ 80.998057][ T5153] last redo_fd_request at 4294945198 [ 80.999910][ T5153] status=80 [ 81.001007][ T5153] fdc_busy=1 [ 81.002150][ T5153] floppy_work.func=floppy_work_workfn [ 81.004642][ T5153] cont=ffffffff8ad02f20 [ 81.006110][ T5153] current_req=0000000000000000 [ 81.007785][ T5153] command_status=-1 [ 81.009146][ T5153] [ 81.010035][ T5153] floppy1: floppy timeout called [ 81.022860][ T5492] floppy1: reschedule timeout lock fdc [ 81.022900][ T5153] [ 81.025529][ T5492] floppy1: setting NEWCHANGE in poll_drive [ 81.026159][ T5153] floppy driver state [ 81.029978][ T5153] ------------------- [ 81.031371][ T5153] now=4294945207 last interrupt=4294944912 diff=295 last called handler=main_command_interrupt [ 81.035160][ T5153] timeout_message=lock fdc [ 81.036730][ T5153] last output bytes: [ 81.038117][ T5153] 0 90 4294944909 [ 81.039430][ T5153] 1 90 4294944909 [ 81.040769][ T5153] 2 90 4294944909 [ 81.042087][ T5153] 12 90 4294944909 [ 81.043405][ T5153] 1b 90 4294944909 [ 81.044955][ T5153] ff 90 4294944909 [ 81.046279][ T5153] 12 80 4294944912 [ 81.047574][ T5153] 3 90 4294944912 [ 81.048913][ T5153] 3 80 4294944912 [ 81.050225][ T5153] a1 90 4294944912 [ 81.051519][ T5153] 1e 90 4294944912 [ 81.052860][ T5153] e6 80 4294944912 [ 81.054301][ T5153] 1 90 4294944912 [ 81.055624][ T5153] 0 90 4294944912 [ 81.056962][ T5153] 0 90 4294944912 [ 81.058296][ T5153] 1 90 4294944912 [ 81.059633][ T5153] 2 90 4294944912 [ 81.060972][ T5153] 24 90 4294944912 [ 81.062305][ T5153] 1b 90 4294944912 [ 81.063710][ T5153] ff 90 4294944912 [ 81.065060][ T5153] last result at 4294944913 [ 81.066657][ T5153] last redo_fd_request at 4294945205 [ 81.068551][ T5153] status=80 [ 81.069698][ T5153] fdc_busy=1 [ 81.070844][ T5153] floppy_work.func=floppy_work_workfn [ 81.072762][ T5153] cont=ffffffff8ad02f20 [ 81.074355][ T5153] current_req=0000000000000000 [ 81.076084][ T5153] command_status=-1 [ 81.077714][ T5153] [ 81.078754][ T5153] floppy1: floppy timeout called [ 81.094477][ T5495] floppy1: reschedule timeout lock fdc [ 81.096983][ T5495] floppy1: setting NEWCHANGE in poll_drive [ 81.099629][ T5153] [ 81.100706][ T5153] floppy driver state [ 81.102451][ T5153] ------------------- [ 81.104471][ T5153] now=4294945215 last interrupt=4294944912 diff=303 last called handler=main_command_interrupt [ 81.108926][ T5153] timeout_message=lock fdc [ 81.110820][ T5153] last output bytes: [ 81.112556][ T5153] 0 90 4294944909 [ 81.114442][ T5153] 1 90 4294944909 [ 81.116019][ T5153] 2 90 4294944909 [ 81.117739][ T5153] 12 90 4294944909 [ 81.119401][ T5153] 1b 90 4294944909 [ 81.121045][ T5153] ff 90 4294944909 [ 81.122668][ T5153] 12 80 4294944912 [ 81.124536][ T5153] 3 90 4294944912 [ 81.126202][ T5153] 3 80 4294944912 [ 81.127817][ T5153] a1 90 4294944912 [ 81.129479][ T5153] 1e 90 4294944912 [ 81.131162][ T5153] e6 80 4294944912 [ 81.132868][ T5153] 1 90 4294944912 [ 81.134624][ T5153] 0 90 4294944912 [ 81.136300][ T5153] 0 90 4294944912 [ 81.137994][ T5153] 1 90 4294944912 [ 81.139645][ T5153] 2 90 4294944912 [ 81.141234][ T5153] 24 90 4294944912 [ 81.142880][ T5153] 1b 90 4294944912 [ 81.144678][ T5153] ff 90 4294944912 [ 81.146313][ T5153] last result at 4294944913 [ 81.148247][ T5153] last redo_fd_request at 4294945212 [ 81.150592][ T5153] status=80 [ 81.152015][ T5153] fdc_busy=1 [ 81.153593][ T5153] floppy_work.func=floppy_work_workfn [ 81.155935][ T5153] cont=ffffffff8ad02f20 [ 81.157732][ T5153] current_req=0000000000000000 [ 81.159769][ T5153] command_status=-1 [ 81.161434][ T5153] [ 81.162513][ T5153] floppy1: floppy timeout called [ 81.182292][ T5498] floppy1: reschedule timeout lock fdc [ 81.184021][ T5219] [ 81.184913][ T5498] floppy1: setting NEWCHANGE in poll_drive [ 81.185491][ T5219] floppy driver state [ 81.185497][ T5219] ------------------- [ 81.191519][ T5219] now=4294945223 last interrupt=4294944912 diff=311 last called handler=main_command_interrupt [ 81.195575][ T5219] timeout_message=lock fdc [ 81.197174][ T5219] last output bytes: [ 81.198558][ T5219] 0 90 4294944909 [ 81.199808][ T5219] 1 90 4294944909 [ 81.201329][ T5219] 2 90 4294944909 [ 81.202640][ T5219] 12 90 4294944909 [ 81.204471][ T5219] 1b 90 4294944909 [ 81.205940][ T5219] ff 90 4294944909 [ 81.207279][ T5219] 12 80 4294944912 [ 81.208608][ T5219] 3 90 4294944912 [ 81.209952][ T5219] 3 80 4294944912 [ 81.211277][ T5219] a1 90 4294944912 [ 81.212599][ T5219] 1e 90 4294944912 [ 81.216233][ T5219] e6 80 4294944912 [ 81.217765][ T5219] 1 90 4294944912 [ 81.219087][ T5219] 0 90 4294944912 [ 81.220376][ T5219] 0 90 4294944912 [ 81.221691][ T5219] 1 90 4294944912 [ 81.223073][ T5219] 2 90 4294944912 [ 81.225017][ T5219] 24 90 4294944912 [ 81.226344][ T5219] 1b 90 4294944912 [ 81.227662][ T5219] ff 90 4294944912 [ 81.228986][ T5219] last result at 4294944913 [ 81.230580][ T5219] last redo_fd_request at 4294945221 [ 81.232442][ T5219] status=80 [ 81.234198][ T5219] fdc_busy=1 [ 81.235356][ T5219] floppy_work.func=floppy_work_workfn [ 81.237223][ T5219] cont=ffffffff8ad02f20 [ 81.238690][ T5219] current_req=0000000000000000 [ 81.240351][ T5219] command_status=-1 [ 81.241709][ T5219] [ 81.242591][ T5219] floppy1: floppy timeout called [ 81.259462][ T5501] floppy1: reschedule timeout lock fdc [ 81.259571][ T5219] [ 81.261801][ T5501] floppy1: setting NEWCHANGE in poll_drive [ 81.262511][ T5219] floppy driver state [ 81.262515][ T5219] ------------------- [ 81.262517][ T5219] now=4294945230 last interrupt=4294944912 diff=318 last called handler=main_command_interrupt [ 81.271401][ T5219] timeout_message=lock fdc [ 81.273170][ T5219] last output bytes: [ 81.274627][ T5219] 0 90 4294944909 [ 81.275951][ T5219] 1 90 4294944909 [ 81.277278][ T5219] 2 90 4294944909 [ 81.278722][ T5219] 12 90 4294944909 [ 81.280043][ T5219] 1b 90 4294944909 [ 81.281378][ T5219] ff 90 4294944909 [ 81.283005][ T5219] 12 80 4294944912 [ 81.284454][ T5219] 3 90 4294944912 [ 81.285803][ T5219] 3 80 4294944912 [ 81.287053][ T5219] a1 90 4294944912 [ 81.288399][ T5219] 1e 90 4294944912 [ 81.289765][ T5219] e6 80 4294944912 [ 81.291225][ T5219] 1 90 4294944912 [ 81.292517][ T5219] 0 90 4294944912 [ 81.293917][ T5219] 0 90 4294944912 [ 81.295270][ T5219] 1 90 4294944912 [ 81.296567][ T5219] 2 90 4294944912 [ 81.297913][ T5219] 24 90 4294944912 [ 81.299220][ T5219] 1b 90 4294944912 [ 81.300533][ T5219] ff 90 4294944912 [ 81.301848][ T5219] last result at 4294944913 [ 81.303479][ T5219] last redo_fd_request at 4294945229 [ 81.305360][ T5219] status=80 [ 81.306476][ T5219] fdc_busy=1 [ 81.307667][ T5219] floppy_work.func=floppy_work_workfn [ 81.310151][ T5219] cont=ffffffff8ad02f20 [ 81.311640][ T5219] current_req=0000000000000000 [ 81.313292][ T5219] command_status=-1 [ 81.315359][ T5219] [ 81.316256][ T5219] floppy1: floppy timeout called [ 81.330719][ T5504] floppy1: reschedule timeout lock fdc [ 81.333169][ T5504] floppy1: setting NEWCHANGE in poll_drive [ 81.333551][ T5219] [ 81.336584][ T5219] floppy driver state [ 81.338248][ T5219] ------------------- [ 81.339873][ T5219] now=4294945238 last interrupt=4294944912 diff=326 last called handler=main_command_interrupt [ 81.343923][ T5219] timeout_message=lock fdc [ 81.345928][ T5219] last output bytes: [ 81.347660][ T5219] 0 90 4294944909 [ 81.349332][ T5219] 1 90 4294944909 [ 81.351007][ T5219] 2 90 4294944909 [ 81.352659][ T5219] 12 90 4294944909 [ 81.354591][ T5219] 1b 90 4294944909 [ 81.356335][ T5219] ff 90 4294944909 [ 81.358010][ T5219] 12 80 4294944912 [ 81.359671][ T5219] 3 90 4294944912 [ 81.361322][ T5219] 3 80 4294944912 [ 81.363000][ T5219] a1 90 4294944912 [ 81.364865][ T5219] 1e 90 4294944912 [ 81.366590][ T5219] e6 80 4294944912 [ 81.368134][ T5219] 1 90 4294944912 [ 81.369461][ T5219] 0 90 4294944912 [ 81.370783][ T5219] 0 90 4294944912 [ 81.372200][ T5219] 1 90 4294944912 [ 81.373812][ T5219] 2 90 4294944912 [ 81.375139][ T5219] 24 90 4294944912 [ 81.376463][ T5219] 1b 90 4294944912 [ 81.377800][ T5219] ff 90 4294944912 [ 81.379444][ T5219] last result at 4294944913 [ 81.381172][ T5219] last redo_fd_request at 4294945236 [ 81.383597][ T5219] status=80 [ 81.384813][ T5219] fdc_busy=1 [ 81.386026][ T5219] floppy_work.func=floppy_work_workfn [ 81.388432][ T5219] cont=ffffffff8ad02f20 [ 81.390267][ T5219] current_req=0000000000000000 [ 81.392385][ T5219] command_status=-1 [ 81.394135][ T5219] [ 81.395202][ T5219] floppy1: floppy timeout called [ 81.409918][ T5507] floppy1: reschedule timeout lock fdc [ 81.412346][ T5507] floppy1: setting NEWCHANGE in poll_drive [ 81.413563][ T5219] [ 81.416048][ T5219] floppy driver state [ 81.417775][ T5219] ------------------- [ 81.419150][ T5219] now=4294945246 last interrupt=4294944912 diff=334 last called handler=main_command_interrupt [ 81.423020][ T5219] timeout_message=lock fdc [ 81.424684][ T5219] last output bytes: [ 81.426113][ T5219] 0 90 4294944909 [ 81.427450][ T5219] 1 90 4294944909 [ 81.428807][ T5219] 2 90 4294944909 [ 81.430146][ T5219] 12 90 4294944909 [ 81.431481][ T5219] 1b 90 4294944909 [ 81.433092][ T5219] ff 90 4294944909 [ 81.434696][ T5219] 12 80 4294944912 [ 81.436033][ T5219] 3 90 4294944912 [ 81.437366][ T5219] 3 80 4294944912 [ 81.438697][ T5219] a1 90 4294944912 [ 81.440004][ T5219] 1e 90 4294944912 [ 81.441334][ T5219] e6 80 4294944912 [ 81.442674][ T5219] 1 90 4294944912 [ 81.444382][ T5219] 0 90 4294944912 [ 81.445846][ T5219] 0 90 4294944912 [ 81.447519][ T5219] 1 90 4294944912 [ 81.449175][ T5219] 2 90 4294944912 [ 81.450777][ T5219] 24 90 4294944912 [ 81.452411][ T5219] 1b 90 4294944912 [ 81.454303][ T5219] ff 90 4294944912 [ 81.456015][ T5219] last result at 4294944913 [ 81.458074][ T5219] last redo_fd_request at 4294945244 [ 81.461172][ T5219] status=80 [ 81.462391][ T5219] fdc_busy=1 [ 81.463743][ T5219] floppy_work.func=floppy_work_workfn [ 81.465663][ T5219] cont=ffffffff8ad02f20 [ 81.467093][ T5219] current_req=0000000000000000 [ 81.468773][ T5219] command_status=-1 [ 81.470120][ T5219] [ 81.471014][ T5219] floppy1: floppy timeout called [ 81.485221][ T5510] floppy1: reschedule timeout lock fdc [ 81.485254][ T5219] [ 81.487130][ T5510] floppy1: setting NEWCHANGE in poll_drive [ 81.490386][ T5219] floppy driver state [ 81.492057][ T5219] ------------------- [ 81.493679][ T5219] now=4294945254 last interrupt=4294944912 diff=342 last called handler=main_command_interrupt [ 81.497319][ T5219] timeout_message=lock fdc [ 81.499129][ T5219] last output bytes: [ 81.500716][ T5219] 0 90 4294944909 [ 81.502206][ T5219] 1 90 4294944909 [ 81.503847][ T5219] 2 90 4294944909 [ 81.505356][ T5219] 12 90 4294944909 [ 81.506946][ T5219] 1b 90 4294944909 [ 81.508283][ T5219] ff 90 4294944909 [ 81.509662][ T5219] 12 80 4294944912 [ 81.511006][ T5219] 3 90 4294944912 [ 81.512367][ T5219] 3 80 4294944912 [ 81.514076][ T5219] a1 90 4294944912 [ 81.515431][ T5219] 1e 90 4294944912 [ 81.516774][ T5219] e6 80 4294944912 [ 81.518093][ T5219] 1 90 4294944912 [ 81.519387][ T5219] 0 90 4294944912 [ 81.520706][ T5219] 0 90 4294944912 [ 81.522032][ T5219] 1 90 4294944912 [ 81.523339][ T5219] 2 90 4294944912 [ 81.524731][ T5219] 24 90 4294944912 [ 81.526067][ T5219] 1b 90 4294944912 [ 81.527378][ T5219] ff 90 4294944912 [ 81.528695][ T5219] last result at 4294944913 [ 81.530279][ T5219] last redo_fd_request at 4294945251 [ 81.532136][ T5219] status=80 [ 81.533305][ T5219] fdc_busy=1 [ 81.534531][ T5219] floppy_work.func=floppy_work_workfn [ 81.536394][ T5219] cont=ffffffff8ad02f20 [ 81.537883][ T5219] current_req=0000000000000000 [ 81.539535][ T5219] command_status=-1 [ 81.540893][ T5219] [ 81.541770][ T5219] floppy1: floppy timeout called [ 81.553708][ T5513] floppy1: reschedule timeout lock fdc [ 81.553732][ T5153] [ 81.556023][ T5513] floppy1: setting NEWCHANGE in poll_drive [ 81.558988][ T5153] floppy driver state [ 81.560391][ T5153] ------------------- [ 81.561801][ T5153] now=4294945260 last interrupt=4294944912 diff=348 last called handler=main_command_interrupt [ 81.565472][ T5153] timeout_message=lock fdc [ 81.567084][ T5153] last output bytes: [ 81.568443][ T5153] 0 90 4294944909 [ 81.569801][ T5153] 1 90 4294944909 [ 81.571158][ T5153] 2 90 4294944909 [ 81.572504][ T5153] 12 90 4294944909 [ 81.574118][ T5153] 1b 90 4294944909 [ 81.575499][ T5153] ff 90 4294944909 [ 81.576907][ T5153] 12 80 4294944912 [ 81.578249][ T5153] 3 90 4294944912 [ 81.579693][ T5153] 3 80 4294944912 [ 81.581321][ T5153] a1 90 4294944912 [ 81.582972][ T5153] 1e 90 4294944912 [ 81.584935][ T5153] e6 80 4294944912 [ 81.586640][ T5153] 1 90 4294944912 [ 81.587992][ T5153] 0 90 4294944912 [ 81.589518][ T5153] 0 90 4294944912 [ 81.591230][ T5153] 1 90 4294944912 [ 81.592888][ T5153] 2 90 4294944912 [ 81.594802][ T5153] 24 90 4294944912 [ 81.596549][ T5153] 1b 90 4294944912 [ 81.597953][ T5153] ff 90 4294944912 [ 81.599288][ T5153] last result at 4294944913 [ 81.600923][ T5153] last redo_fd_request at 4294945259 [ 81.602864][ T5153] status=80 [ 81.604122][ T5153] fdc_busy=1 [ 81.605347][ T5153] floppy_work.func=floppy_work_workfn [ 81.607311][ T5153] cont=ffffffff8ad02f20 [ 81.608831][ T5153] current_req=0000000000000000 [ 81.610516][ T5153] command_status=-1 [ 81.611869][ T5153] [ 81.612820][ T5153] floppy1: floppy timeout called [ 81.624598][ T5516] floppy1: reschedule timeout lock fdc [ 81.624633][ T5153] [ 81.626612][ T5516] floppy1: setting NEWCHANGE in poll_drive [ 81.630253][ T5153] floppy driver state [ 81.631981][ T5153] ------------------- [ 81.633834][ T5153] now=4294945268 last interrupt=4294944912 diff=356 last called handler=main_command_interrupt [ 81.638102][ T5153] timeout_message=lock fdc [ 81.640002][ T5153] last output bytes: [ 81.641681][ T5153] 0 90 4294944909 [ 81.643245][ T5153] 1 90 4294944909 [ 81.644889][ T5153] 2 90 4294944909 [ 81.646221][ T5153] 12 90 4294944909 [ 81.647580][ T5153] 1b 90 4294944909 [ 81.648966][ T5153] ff 90 4294944909 [ 81.650336][ T5153] 12 80 4294944912 [ 81.651672][ T5153] 3 90 4294944912 [ 81.653284][ T5153] 3 80 4294944912 [ 81.655069][ T5153] a1 90 4294944912 [ 81.656768][ T5153] 1e 90 4294944912 [ 81.658276][ T5153] e6 80 4294944912 [ 81.659698][ T5153] 1 90 4294944912 [ 81.661092][ T5153] 0 90 4294944912 [ 81.662595][ T5153] 0 90 4294944912 [ 81.664011][ T5153] 1 90 4294944912 [ 81.665375][ T5153] 2 90 4294944912 [ 81.666733][ T5153] 24 90 4294944912 [ 81.668250][ T5153] 1b 90 4294944912 [ 81.669800][ T5153] ff 90 4294944912 [ 81.671326][ T5153] last result at 4294944913 [ 81.673088][ T5153] last redo_fd_request at 4294945266 [ 81.675587][ T5153] status=80 [ 81.676987][ T5153] fdc_busy=1 [ 81.678140][ T5153] floppy_work.func=floppy_work_workfn [ 81.680042][ T5153] cont=ffffffff8ad02f20 [ 81.681495][ T5153] current_req=0000000000000000 [ 81.683227][ T5153] command_status=-1 [ 81.684689][ T5153] [ 81.685669][ T5153] floppy1: floppy timeout called [ 81.701638][ T5519] floppy1: reschedule timeout lock fdc [ 81.701719][ T10] [ 81.704256][ T5519] floppy1: setting NEWCHANGE in poll_drive [ 81.705038][ T10] floppy driver state [ 81.705043][ T10] ------------------- [ 81.705046][ T10] now=4294945275 last interrupt=4294944912 diff=363 last called handler=main_command_interrupt [ 81.714263][ T10] timeout_message=lock fdc [ 81.715848][ T10] last output bytes: [ 81.717226][ T10] 0 90 4294944909 [ 81.718664][ T10] 1 90 4294944909 [ 81.719978][ T10] 2 90 4294944909 [ 81.721304][ T10] 12 90 4294944909 [ 81.722634][ T10] 1b 90 4294944909 [ 81.724331][ T10] ff 90 4294944909 [ 81.725824][ T10] 12 80 4294944912 [ 81.727138][ T10] 3 90 4294944912 [ 81.728444][ T10] 3 80 4294944912 [ 81.729769][ T10] a1 90 4294944912 [ 81.731086][ T10] 1e 90 4294944912 [ 81.732484][ T10] e6 80 4294944912 [ 81.734180][ T10] 1 90 4294944912 [ 81.735538][ T10] 0 90 4294944912 [ 81.736900][ T10] 0 90 4294944912 [ 81.738336][ T10] 1 90 4294944912 [ 81.739677][ T10] 2 90 4294944912 [ 81.741035][ T10] 24 90 4294944912 [ 81.742383][ T10] 1b 90 4294944912 [ 81.743980][ T10] ff 90 4294944912 [ 81.745461][ T10] last result at 4294944913 [ 81.747144][ T10] last redo_fd_request at 4294945273 [ 81.749143][ T10] status=80 [ 81.750284][ T10] fdc_busy=1 [ 81.752027][ T10] floppy_work.func=floppy_work_workfn [ 81.755766][ T1343] ieee802154 phy0 wpan0: encryption failed: -22 [ 81.755827][ T10] cont=ffffffff8ad02f20 [ 81.758095][ T1343] ieee802154 phy1 wpan1: encryption failed: -22 [ 81.759612][ T10] current_req=0000000000000000 [ 81.764134][ T10] command_status=-1 [ 81.765584][ T10] [ 81.766490][ T10] floppy1: floppy timeout called [ 81.779595][ T5522] floppy1: reschedule timeout lock fdc [ 81.779625][ T10] [ 81.781698][ T5522] floppy1: setting NEWCHANGE in poll_drive [ 81.782756][ T10] floppy driver state [ 81.786678][ T10] ------------------- [ 81.788097][ T10] now=4294945283 last interrupt=4294944912 diff=371 last called handler=main_command_interrupt [ 81.791687][ T10] timeout_message=lock fdc [ 81.793300][ T10] last output bytes: [ 81.794925][ T10] 0 90 4294944909 [ 81.796231][ T10] 1 90 4294944909 [ 81.797562][ T10] 2 90 4294944909 [ 81.798867][ T10] 12 90 4294944909 [ 81.800174][ T10] 1b 90 4294944909 [ 81.801491][ T10] ff 90 4294944909 [ 81.802994][ T10] 12 80 4294944912 [ 81.804805][ T10] 3 90 4294944912 [ 81.806338][ T10] 3 80 4294944912 [ 81.808000][ T10] a1 90 4294944912 [ 81.809701][ T10] 1e 90 4294944912 [ 81.811368][ T10] e6 80 4294944912 [ 81.813055][ T10] 1 90 4294944912 [ 81.814868][ T10] 0 90 4294944912 [ 81.816321][ T10] 0 90 4294944912 [ 81.817643][ T10] 1 90 4294944912 [ 81.819048][ T10] 2 90 4294944912 [ 81.820453][ T10] 24 90 4294944912 [ 81.822022][ T10] 1b 90 4294944912 [ 81.823626][ T10] ff 90 4294944912 [ 81.824964][ T10] last result at 4294944913 [ 81.826546][ T10] last redo_fd_request at 4294945281 [ 81.828402][ T10] status=80 [ 81.829711][ T10] fdc_busy=1 [ 81.830850][ T10] floppy_work.func=floppy_work_workfn [ 81.832797][ T10] cont=ffffffff8ad02f20 [ 81.834351][ T10] current_req=0000000000000000 [ 81.836320][ T10] command_status=-1 [ 81.837772][ T10] [ 81.838643][ T10] floppy1: floppy timeout called [ 81.851977][ T5525] floppy1: reschedule timeout lock fdc [ 81.852010][ T10] [ 81.854472][ T5525] floppy1: setting NEWCHANGE in poll_drive [ 81.855178][ T10] floppy driver state [ 81.855183][ T10] ------------------- [ 81.855186][ T10] now=4294945290 last interrupt=4294944912 diff=378 last called handler=main_command_interrupt [ 81.864853][ T10] timeout_message=lock fdc [ 81.866714][ T10] last output bytes: [ 81.868129][ T10] 0 90 4294944909 [ 81.869450][ T10] 1 90 4294944909 [ 81.870803][ T10] 2 90 4294944909 [ 81.872196][ T10] 12 90 4294944909 [ 81.873719][ T10] 1b 90 4294944909 [ 81.875288][ T10] ff 90 4294944909 [ 81.876708][ T10] 12 80 4294944912 [ 81.878170][ T10] 3 90 4294944912 [ 81.879500][ T10] 3 80 4294944912 [ 81.880844][ T10] a1 90 4294944912 [ 81.882244][ T10] 1e 90 4294944912 [ 81.883715][ T10] e6 80 4294944912 [ 81.885093][ T10] 1 90 4294944912 [ 81.886433][ T10] 0 90 4294944912 [ 81.887862][ T10] 0 90 4294944912 [ 81.889177][ T10] 1 90 4294944912 [ 81.890577][ T10] 2 90 4294944912 [ 81.891969][ T10] 24 90 4294944912 [ 81.893394][ T10] 1b 90 4294944912 [ 81.894859][ T10] ff 90 4294944912 [ 81.896383][ T10] last result at 4294944913 [ 81.898058][ T10] last redo_fd_request at 4294945288 [ 81.899933][ T10] status=80 [ 81.901102][ T10] fdc_busy=1 [ 81.902302][ T10] floppy_work.func=floppy_work_workfn [ 81.904400][ T10] cont=ffffffff8ad02f20 [ 81.905893][ T10] current_req=0000000000000000 [ 81.907578][ T10] command_status=-1 [ 81.908956][ T10] [ 81.909854][ T10] floppy1: floppy timeout called [ 81.924392][ T5528] floppy1: reschedule timeout lock fdc [ 81.924824][ T10] [ 81.926903][ T5528] floppy1: setting NEWCHANGE in poll_drive [ 81.928000][ T10] floppy driver state [ 81.931747][ T10] ------------------- [ 81.933713][ T10] now=4294945298 last interrupt=4294944912 diff=386 last called handler=main_command_interrupt [ 81.937451][ T10] timeout_message=lock fdc [ 81.939024][ T10] last output bytes: [ 81.940383][ T10] 0 90 4294944909 [ 81.941716][ T10] 1 90 4294944909 [ 81.943044][ T10] 2 90 4294944909 [ 81.944497][ T10] 12 90 4294944909 [ 81.945822][ T10] 1b 90 4294944909 [ 81.947131][ T10] ff 90 4294944909 [ 81.948435][ T10] 12 80 4294944912 [ 81.949765][ T10] 3 90 4294944912 [ 81.951079][ T10] 3 80 4294944912 [ 81.952384][ T10] a1 90 4294944912 [ 81.953784][ T10] 1e 90 4294944912 [ 81.955158][ T10] e6 80 4294944912 [ 81.956850][ T10] 1 90 4294944912 [ 81.958233][ T10] 0 90 4294944912 [ 81.959555][ T10] 0 90 4294944912 [ 81.961098][ T10] 1 90 4294944912 [ 81.962803][ T10] 2 90 4294944912 [ 81.964638][ T10] 24 90 4294944912 [ 81.966303][ T10] 1b 90 4294944912 [ 81.967968][ T10] ff 90 4294944912 [ 81.969568][ T10] last result at 4294944913 [ 81.971276][ T10] last redo_fd_request at 4294945295 [ 81.973297][ T10] status=80 [ 81.974831][ T10] fdc_busy=1 [ 81.976300][ T10] floppy_work.func=floppy_work_workfn [ 81.978687][ T10] cont=ffffffff8ad02f20 [ 81.980538][ T10] current_req=0000000000000000 [ 81.982689][ T10] command_status=-1 [ 81.984411][ T10] [ 81.985492][ T10] floppy1: floppy timeout called [ 81.999889][ T5531] floppy1: reschedule timeout lock fdc [ 82.001964][ T5531] floppy1: setting NEWCHANGE in poll_drive [ 82.003935][ T5153] [ 82.005495][ T5153] floppy driver state [ 82.006952][ T5153] ------------------- [ 82.008593][ T5153] now=4294945305 last interrupt=4294944912 diff=393 last called handler=main_command_interrupt [ 82.012683][ T5153] timeout_message=lock fdc [ 82.014606][ T5153] last output bytes: [ 82.016160][ T5153] 0 90 4294944909 [ 82.017648][ T5153] 1 90 4294944909 [ 82.019243][ T5153] 2 90 4294944909 [ 82.020800][ T5153] 12 90 4294944909 [ 82.022446][ T5153] 1b 90 4294944909 [ 82.023980][ T5153] ff 90 4294944909 [ 82.025313][ T5153] 12 80 4294944912 [ 82.026622][ T5153] 3 90 4294944912 [ 82.027937][ T5153] 3 80 4294944912 [ 82.029260][ T5153] a1 90 4294944912 [ 82.030608][ T5153] 1e 90 4294944912 [ 82.031984][ T5153] e6 80 4294944912 [ 82.033382][ T5153] 1 90 4294944912 [ 82.035612][ T5153] 0 90 4294944912 [ 82.036987][ T5153] 0 90 4294944912 [ 82.038338][ T5153] 1 90 4294944912 [ 82.039668][ T5153] 2 90 4294944912 [ 82.041178][ T5153] 24 90 4294944912 [ 82.042931][ T5153] 1b 90 4294944912 [ 82.044613][ T5153] ff 90 4294944912 [ 82.046103][ T5153] last result at 4294944913 [ 82.048136][ T5153] last redo_fd_request at 4294945303 [ 82.050540][ T5153] status=80 [ 82.051959][ T5153] fdc_busy=1 [ 82.053397][ T5153] floppy_work.func=floppy_work_workfn [ 82.055851][ T5153] cont=ffffffff8ad02f20 [ 82.057696][ T5153] current_req=0000000000000000 [ 82.059355][ T5153] command_status=-1 [ 82.060704][ T5153] [ 82.061621][ T5153] floppy1: floppy timeout called [ 82.076487][ T5534] floppy1: reschedule timeout lock fdc [ 82.076531][ T5153] [ 82.078897][ T5534] floppy1: setting NEWCHANGE in poll_drive [ 82.079686][ T5153] floppy driver state [ 82.079690][ T5153] ------------------- [ 82.079693][ T5153] now=4294945312 last interrupt=4294944912 diff=400 last called handler=main_command_interrupt [ 82.079709][ T5153] timeout_message=lock fdc [ 82.090452][ T5153] last output bytes: [ 82.091870][ T5153] 0 90 4294944909 [ 82.093580][ T5153] 1 90 4294944909 [ 82.095173][ T5153] 2 90 4294944909 [ 82.096780][ T5153] 12 90 4294944909 [ 82.098086][ T5153] 1b 90 4294944909 [ 82.099542][ T5153] ff 90 4294944909 [ 82.101134][ T5153] 12 80 4294944912 [ 82.102424][ T5153] 3 90 4294944912 [ 82.103772][ T5153] 3 80 4294944912 [ 82.105360][ T5153] a1 90 4294944912 [ 82.106757][ T5153] 1e 90 4294944912 [ 82.108057][ T5153] e6 80 4294944912 [ 82.109391][ T5153] 1 90 4294944912 [ 82.110706][ T5153] 0 90 4294944912 [ 82.112307][ T5153] 0 90 4294944912 [ 82.113947][ T5153] 1 90 4294944912 [ 82.115290][ T5153] 2 90 4294944912 [ 82.116620][ T5153] 24 90 4294944912 [ 82.117987][ T5153] 1b 90 4294944912 [ 82.119315][ T5153] ff 90 4294944912 [ 82.120642][ T5153] last result at 4294944913 [ 82.122525][ T5153] last redo_fd_request at 4294945311 [ 82.124953][ T5153] status=80 [ 82.127562][ T5153] fdc_busy=1 [ 82.128931][ T5153] floppy_work.func=floppy_work_workfn [ 82.130797][ T5153] cont=ffffffff8ad02f20 [ 82.132239][ T5153] current_req=0000000000000000 [ 82.134146][ T5153] command_status=-1 [ 82.135470][ T5153] [ 82.136326][ T5153] floppy1: floppy timeout called [ 82.150875][ T5537] floppy1: reschedule timeout lock fdc [ 82.150913][ T5153] [ 82.153325][ T5537] floppy1: setting NEWCHANGE in poll_drive [ 82.154829][ T5153] floppy driver state [ 82.158636][ T5153] ------------------- [ 82.160271][ T5153] now=4294945320 last interrupt=4294944912 diff=408 last called handler=main_command_interrupt [ 82.164520][ T5153] timeout_message=lock fdc [ 82.166353][ T5153] last output bytes: [ 82.167990][ T5153] 0 90 4294944909 [ 82.169570][ T5153] 1 90 4294944909 [ 82.171101][ T5153] 2 90 4294944909 [ 82.172666][ T5153] 12 90 4294944909 [ 82.174375][ T5153] 1b 90 4294944909 [ 82.175973][ T5153] ff 90 4294944909 [ 82.177547][ T5153] 12 80 4294944912 [ 82.179111][ T5153] 3 90 4294944912 [ 82.180674][ T5153] 3 80 4294944912 [ 82.182218][ T5153] a1 90 4294944912 [ 82.183838][ T5153] 1e 90 4294944912 [ 82.185384][ T5153] e6 80 4294944912 [ 82.186928][ T5153] 1 90 4294944912 [ 82.188555][ T5153] 0 90 4294944912 [ 82.190040][ T5153] 0 90 4294944912 [ 82.191394][ T5153] 1 90 4294944912 [ 82.192710][ T5153] 2 90 4294944912 [ 82.194131][ T5153] 24 90 4294944912 [ 82.195446][ T5153] 1b 90 4294944912 [ 82.196773][ T5153] ff 90 4294944912 [ 82.198109][ T5153] last result at 4294944913 [ 82.199765][ T5153] last redo_fd_request at 4294945318 [ 82.202368][ T5153] status=80 [ 82.203867][ T5153] fdc_busy=1 [ 82.205178][ T5153] floppy_work.func=floppy_work_workfn [ 82.206989][ T5153] cont=ffffffff8ad02f20 [ 82.208345][ T5153] current_req=0000000000000000 [ 82.210270][ T5153] command_status=-1 [ 82.211790][ T5153] [ 82.212827][ T5153] floppy1: floppy timeout called [ 82.226419][ T5540] floppy1: reschedule timeout lock fdc [ 82.226460][ T5153] [ 82.228642][ T5540] floppy1: setting NEWCHANGE in poll_drive [ 82.229429][ T5153] floppy driver state [ 82.229434][ T5153] ------------------- [ 82.235356][ T5153] now=4294945328 last interrupt=4294944912 diff=416 last called handler=main_command_interrupt [ 82.239642][ T5153] timeout_message=lock fdc [ 82.241652][ T5153] last output bytes: [ 82.243516][ T5153] 0 90 4294944909 [ 82.245261][ T5153] 1 90 4294944909 [ 82.246936][ T5153] 2 90 4294944909 [ 82.248597][ T5153] 12 90 4294944909 [ 82.250266][ T5153] 1b 90 4294944909 [ 82.251921][ T5153] ff 90 4294944909 [ 82.253758][ T5153] 12 80 4294944912 [ 82.255387][ T5153] 3 90 4294944912 [ 82.257084][ T5153] 3 80 4294944912 [ 82.258710][ T5153] a1 90 4294944912 [ 82.260371][ T5153] 1e 90 4294944912 [ 82.262019][ T5153] e6 80 4294944912 [ 82.263532][ T5153] 1 90 4294944912 [ 82.264973][ T5153] 0 90 4294944912 [ 82.266426][ T5153] 0 90 4294944912 [ 82.268016][ T5153] 1 90 4294944912 [ 82.269396][ T5153] 2 90 4294944912 [ 82.270740][ T5153] 24 90 4294944912 [ 82.272148][ T5153] 1b 90 4294944912 [ 82.273642][ T5153] ff 90 4294944912 [ 82.275063][ T5153] last result at 4294944913 [ 82.276767][ T5153] last redo_fd_request at 4294945326 [ 82.278730][ T5153] status=80 [ 82.280060][ T5153] fdc_busy=1 [ 82.281432][ T5153] floppy_work.func=floppy_work_workfn [ 82.283701][ T5153] cont=ffffffff8ad02f20 [ 82.285489][ T5153] current_req=0000000000000000 [ 82.287367][ T5153] command_status=-1 [ 82.288845][ T5153] [ 82.289911][ T5153] floppy1: floppy timeout called [ 82.304431][ T5543] floppy1: reschedule timeout lock fdc [ 82.304459][ T5219] [ 82.306573][ T5543] floppy1: setting NEWCHANGE in poll_drive [ 82.307526][ T5219] floppy driver state [ 82.311568][ T5219] ------------------- [ 82.313285][ T5219] now=4294945335 last interrupt=4294944912 diff=423 last called handler=main_command_interrupt [ 82.318095][ T5219] timeout_message=lock fdc [ 82.320046][ T5219] last output bytes: [ 82.321667][ T5219] 0 90 4294944909 [ 82.323257][ T5219] 1 90 4294944909 [ 82.325717][ T5219] 2 90 4294944909 [ 82.327358][ T5219] 12 90 4294944909 [ 82.329003][ T5219] 1b 90 4294944909 [ 82.330597][ T5219] ff 90 4294944909 [ 82.332123][ T5219] 12 80 4294944912 [ 82.333876][ T5219] 3 90 4294944912 [ 82.335487][ T5219] 3 80 4294944912 [ 82.337094][ T5219] a1 90 4294944912 [ 82.338690][ T5219] 1e 90 4294944912 [ 82.340318][ T5219] e6 80 4294944912 [ 82.342013][ T5219] 1 90 4294944912 [ 82.343873][ T5219] 0 90 4294944912 [ 82.345536][ T5219] 0 90 4294944912 [ 82.347202][ T5219] 1 90 4294944912 [ 82.348892][ T5219] 2 90 4294944912 [ 82.350576][ T5219] 24 90 4294944912 [ 82.351956][ T5219] 1b 90 4294944912 [ 82.353768][ T5219] ff 90 4294944912 [ 82.355497][ T5219] last result at 4294944913 [ 82.357531][ T5219] last redo_fd_request at 4294945333 [ 82.359897][ T5219] status=80 [ 82.361296][ T5219] fdc_busy=1 [ 82.362569][ T5219] floppy_work.func=floppy_work_workfn [ 82.365011][ T5219] cont=ffffffff8ad02f20 [ 82.366850][ T5219] current_req=0000000000000000 [ 82.368930][ T5219] command_status=-1 [ 82.370637][ T5219] [ 82.371744][ T5219] floppy1: floppy timeout called [ 82.385544][ T5546] floppy1: reschedule timeout lock fdc [ 82.387816][ T5546] floppy1: setting NEWCHANGE in poll_drive [ 82.389722][ T5219] [ 82.390573][ T5219] floppy driver state [ 82.391983][ T5219] ------------------- [ 82.393664][ T5219] now=4294945344 last interrupt=4294944912 diff=432 last called handler=main_command_interrupt [ 82.398042][ T5219] timeout_message=lock fdc [ 82.399975][ T5219] last output bytes: [ 82.401667][ T5219] 0 90 4294944909 [ 82.403268][ T5219] 1 90 4294944909 [ 82.405625][ T5219] 2 90 4294944909 [ 82.407270][ T5219] 12 90 4294944909 [ 82.408961][ T5219] 1b 90 4294944909 [ 82.410343][ T5219] ff 90 4294944909 [ 82.411881][ T5219] 12 80 4294944912 [ 82.413344][ T5219] 3 90 4294944912 [ 82.414883][ T5219] 3 80 4294944912 [ 82.416215][ T5219] a1 90 4294944912 [ 82.417569][ T5219] 1e 90 4294944912 [ 82.419001][ T5219] e6 80 4294944912 [ 82.420465][ T5219] 1 90 4294944912 [ 82.422031][ T5219] 0 90 4294944912 [ 82.423687][ T5219] 0 90 4294944912 [ 82.425070][ T5219] 1 90 4294944912 [ 82.426412][ T5219] 2 90 4294944912 [ 82.427761][ T5219] 24 90 4294944912 [ 82.429143][ T5219] 1b 90 4294944912 [ 82.430489][ T5219] ff 90 4294944912 [ 82.431838][ T5219] last result at 4294944913 [ 82.433666][ T5219] last redo_fd_request at 4294945342 [ 82.435711][ T5219] status=80 [ 82.436984][ T5219] fdc_busy=1 [ 82.438284][ T5219] floppy_work.func=floppy_work_workfn [ 82.440381][ T5219] cont=ffffffff8ad02f20 [ 82.442232][ T5219] current_req=0000000000000000 [ 82.444255][ T5219] command_status=-1 [ 82.445617][ T5219] [ 82.446506][ T5219] floppy1: floppy timeout called [ 82.461194][ T5549] floppy1: reschedule timeout lock fdc [ 82.463555][ T5549] floppy1: setting NEWCHANGE in poll_drive [ 82.464165][ T5219] [ 82.466864][ T5219] floppy driver state [ 82.468434][ T5219] ------------------- [ 82.470118][ T5219] now=4294945351 last interrupt=4294944912 diff=439 last called handler=main_command_interrupt [ 82.474240][ T5219] timeout_message=lock fdc [ 82.475861][ T5219] last output bytes: [ 82.477551][ T5219] 0 90 4294944909 [ 82.479073][ T5219] 1 90 4294944909 [ 82.480502][ T5219] 2 90 4294944909 [ 82.481871][ T5219] 12 90 4294944909 [ 82.483180][ T5219] 1b 90 4294944909 [ 82.484844][ T5219] ff 90 4294944909 [ 82.486313][ T5219] 12 80 4294944912 [ 82.487636][ T5219] 3 90 4294944912 [ 82.488984][ T5219] 3 80 4294944912 [ 82.490316][ T5219] a1 90 4294944912 [ 82.491625][ T5219] 1e 90 4294944912 [ 82.492951][ T5219] e6 80 4294944912 [ 82.494336][ T5219] 1 90 4294944912 [ 82.495660][ T5219] 0 90 4294944912 [ 82.497097][ T5219] 0 90 4294944912 [ 82.498417][ T5219] 1 90 4294944912 [ 82.499720][ T5219] 2 90 4294944912 [ 82.501046][ T5219] 24 90 4294944912 [ 82.502352][ T5219] 1b 90 4294944912 [ 82.503739][ T5219] ff 90 4294944912 [ 82.505108][ T5219] last result at 4294944913 [ 82.507029][ T5219] last redo_fd_request at 4294945349 [ 82.509326][ T5219] status=80 [ 82.510553][ T5219] fdc_busy=1 [ 82.511760][ T5219] floppy_work.func=floppy_work_workfn [ 82.513923][ T5219] cont=ffffffff8ad02f20 [ 82.515482][ T5219] current_req=0000000000000000 [ 82.517347][ T5219] command_status=-1 [ 82.518882][ T5219] [ 82.519896][ T5219] floppy1: floppy timeout called [ 82.540305][ T5552] floppy1: reschedule timeout lock fdc [ 82.540656][ T5219] [ 82.542721][ T5552] floppy1: setting NEWCHANGE in poll_drive [ 82.543920][ T5219] floppy driver state [ 82.548197][ T5219] ------------------- [ 82.549897][ T5219] now=4294945359 last interrupt=4294944912 diff=447 last called handler=main_command_interrupt [ 82.554672][ T5219] timeout_message=lock fdc [ 82.556602][ T5219] last output bytes: [ 82.558290][ T5219] 0 90 4294944909 [ 82.559910][ T5219] 1 90 4294944909 [ 82.561488][ T5219] 2 90 4294944909 [ 82.563112][ T5219] 12 90 4294944909 [ 82.564854][ T5219] 1b 90 4294944909 [ 82.566436][ T5219] ff 90 4294944909 [ 82.568033][ T5219] 12 80 4294944912 [ 82.569661][ T5219] 3 90 4294944912 [ 82.571154][ T5219] 3 80 4294944912 [ 82.572757][ T5219] a1 90 4294944912 [ 82.574532][ T5219] 1e 90 4294944912 [ 82.576136][ T5219] e6 80 4294944912 [ 82.577826][ T5219] 1 90 4294944912 [ 82.579477][ T5219] 0 90 4294944912 [ 82.580990][ T5219] 0 90 4294944912 [ 82.582481][ T5219] 1 90 4294944912 [ 82.584242][ T5219] 2 90 4294944912 [ 82.585941][ T5219] 24 90 4294944912 [ 82.587611][ T5219] 1b 90 4294944912 [ 82.589177][ T5219] ff 90 4294944912 [ 82.591692][ T5219] last result at 4294944913 [ 82.593880][ T5219] last redo_fd_request at 4294945356 [ 82.596210][ T5219] status=80 [ 82.597609][ T5219] fdc_busy=1 [ 82.599038][ T5219] floppy_work.func=floppy_work_workfn [ 82.601296][ T5219] cont=ffffffff8ad02f20 [ 82.603088][ T5219] current_req=0000000000000000 [ 82.605331][ T5219] command_status=-1 [ 82.607009][ T5219] [ 82.608102][ T5219] floppy1: floppy timeout called [ 82.630828][ T5555] floppy1: reschedule timeout lock fdc [ 82.630865][ T5219] [ 82.632997][ T5555] floppy1: setting NEWCHANGE in poll_drive [ 82.634267][ T5219] floppy driver state [ 82.638097][ T5219] ------------------- [ 82.639746][ T5219] now=4294945368 last interrupt=4294944912 diff=456 last called handler=main_command_interrupt [ 82.644242][ T5219] timeout_message=lock fdc [ 82.646175][ T5219] last output bytes: [ 82.647866][ T5219] 0 90 4294944909 [ 82.649484][ T5219] 1 90 4294944909 [ 82.651061][ T5219] 2 90 4294944909 [ 82.652645][ T5219] 12 90 4294944909 [ 82.654349][ T5219] 1b 90 4294944909 [ 82.655662][ T5219] ff 90 4294944909 [ 82.656975][ T5219] 12 80 4294944912 [ 82.658302][ T5219] 3 90 4294944912 [ 82.659626][ T5219] 3 80 4294944912 [ 82.660989][ T5219] a1 90 4294944912 [ 82.662381][ T5219] 1e 90 4294944912 [ 82.664241][ T5219] e6 80 4294944912 [ 82.665856][ T5219] 1 90 4294944912 [ 82.667464][ T5219] 0 90 4294944912 [ 82.669089][ T5219] 0 90 4294944912 [ 82.670634][ T5219] 1 90 4294944912 [ 82.672236][ T5219] 2 90 4294944912 [ 82.673775][ T5219] 24 90 4294944912 [ 82.675086][ T5219] 1b 90 4294944912 [ 82.676496][ T5219] ff 90 4294944912 [ 82.677949][ T5219] last result at 4294944913 [ 82.679631][ T5219] last redo_fd_request at 4294945365 [ 82.682412][ T5219] status=80 [ 82.683642][ T5219] fdc_busy=1 [ 82.685072][ T5219] floppy_work.func=floppy_work_workfn [ 82.687374][ T5219] cont=ffffffff8ad02f20 [ 82.689247][ T5219] current_req=0000000000000000 [ 82.691306][ T5219] command_status=-1 [ 82.692997][ T5219] [ 82.694532][ T5219] floppy1: floppy timeout called [ 82.711273][ T5558] floppy1: reschedule timeout lock fdc [ 82.713955][ T5219] [ 82.714156][ T5558] floppy1: setting NEWCHANGE in poll_drive [ 82.715080][ T5219] floppy driver state [ 82.715087][ T5219] ------------------- [ 82.715092][ T5219] now=4294945376 last interrupt=4294944912 diff=464 last called handler=main_command_interrupt [ 82.724351][ T5219] timeout_message=lock fdc [ 82.726205][ T5219] last output bytes: [ 82.727870][ T5219] 0 90 4294944909 [ 82.729534][ T5219] 1 90 4294944909 [ 82.731218][ T5219] 2 90 4294944909 [ 82.732606][ T5219] 12 90 4294944909 [ 82.734364][ T5219] 1b 90 4294944909 [ 82.736095][ T5219] ff 90 4294944909 [ 82.737658][ T5219] 12 80 4294944912 [ 82.738983][ T5219] 3 90 4294944912 [ 82.740313][ T5219] 3 80 4294944912 [ 82.741655][ T5219] a1 90 4294944912 [ 82.743010][ T5219] 1e 90 4294944912 [ 82.744595][ T5219] e6 80 4294944912 [ 82.746258][ T5219] 1 90 4294944912 [ 82.747841][ T5219] 0 90 4294944912 [ 82.749334][ T5219] 0 90 4294944912 [ 82.750660][ T5219] 1 90 4294944912 [ 82.752103][ T5219] 2 90 4294944912 [ 82.753775][ T5219] 24 90 4294944912 [ 82.755425][ T5219] 1b 90 4294944912 [ 82.757069][ T5219] ff 90 4294944912 [ 82.758401][ T5219] last result at 4294944913 [ 82.759969][ T5219] last redo_fd_request at 4294945374 [ 82.762516][ T5219] status=80 [ 82.764007][ T5219] fdc_busy=1 [ 82.765229][ T5219] floppy_work.func=floppy_work_workfn [ 82.767075][ T5219] cont=ffffffff8ad02f20 [ 82.768520][ T5219] current_req=0000000000000000 [ 82.770199][ T5219] command_status=-1 [ 82.771516][ T5219] [ 82.772380][ T5219] floppy1: floppy timeout called [ 82.786454][ T5561] floppy1: reschedule timeout lock fdc [ 82.788393][ T5219] [ 82.789249][ T5219] floppy driver state [ 82.789942][ T5561] floppy1: setting NEWCHANGE in poll_drive [ 82.790610][ T5219] ------------------- [ 82.790614][ T5219] now=4294945383 last interrupt=4294944912 diff=471 last called handler=main_command_interrupt [ 82.798539][ T5219] timeout_message=lock fdc [ 82.800093][ T5219] last output bytes: [ 82.801456][ T5219] 0 90 4294944909 [ 82.802761][ T5219] 1 90 4294944909 [ 82.804775][ T5219] 2 90 4294944909 [ 82.806181][ T5219] 12 90 4294944909 [ 82.807722][ T5219] 1b 90 4294944909 [ 82.809240][ T5219] ff 90 4294944909 [ 82.810543][ T5219] 12 80 4294944912 [ 82.811861][ T5219] 3 90 4294944912 [ 82.813196][ T5219] 3 80 4294944912 [ 82.815142][ T5219] a1 90 4294944912 [ 82.816870][ T5219] 1e 90 4294944912 [ 82.818337][ T5219] e6 80 4294944912 [ 82.819911][ T5219] 1 90 4294944912 [ 82.821483][ T5219] 0 90 4294944912 [ 82.823018][ T5219] 0 90 4294944912 [ 82.824576][ T5219] 1 90 4294944912 [ 82.826028][ T5219] 2 90 4294944912 [ 82.827608][ T5219] 24 90 4294944912 [ 82.829062][ T5219] 1b 90 4294944912 [ 82.830488][ T5219] ff 90 4294944912 [ 82.832019][ T5219] last result at 4294944913 [ 82.833890][ T5219] last redo_fd_request at 4294945382 [ 82.835965][ T5219] status=80 [ 82.837138][ T5219] fdc_busy=1 [ 82.838488][ T5219] floppy_work.func=floppy_work_workfn [ 82.840529][ T5219] cont=ffffffff8ad02f20 [ 82.842096][ T5219] current_req=0000000000000000 [ 82.844108][ T5219] command_status=-1 [ 82.845735][ T5219] [ 82.846778][ T5219] floppy1: floppy timeout called [ 82.859868][ T5564] floppy1: reschedule timeout lock fdc [ 82.861923][ T5564] floppy1: setting NEWCHANGE in poll_drive [ 82.863677][ T5219] [ 82.865132][ T5219] floppy driver state [ 82.866913][ T5219] ------------------- [ 82.868695][ T5219] now=4294945391 last interrupt=4294944912 diff=479 last called handler=main_command_interrupt [ 82.873203][ T5219] timeout_message=lock fdc [ 82.875486][ T5134] Bluetooth: hci0: command 0x040f tx timeout [ 82.875835][ T5219] last output bytes: [ 82.879279][ T5219] 0 90 4294944909 [ 82.880719][ T5219] 1 90 4294944909 [ 82.882303][ T5219] 2 90 4294944909 [ 82.883957][ T5219] 12 90 4294944909 [ 82.885368][ T5219] 1b 90 4294944909 [ 82.886825][ T5219] ff 90 4294944909 [ 82.888339][ T5219] 12 80 4294944912 [ 82.889892][ T5219] 3 90 4294944912 [ 82.891440][ T5219] 3 80 4294944912 [ 82.893000][ T5219] a1 90 4294944912 [ 82.894473][ T5219] 1e 90 4294944912 [ 82.895824][ T5219] e6 80 4294944912 [ 82.897164][ T5219] 1 90 4294944912 [ 82.898494][ T5219] 0 90 4294944912 [ 82.899824][ T5219] 0 90 4294944912 [ 82.901157][ T5219] 1 90 4294944912 [ 82.902762][ T5219] 2 90 4294944912 [ 82.904313][ T5219] 24 90 4294944912 [ 82.905884][ T5219] 1b 90 4294944912 [ 82.907241][ T5219] ff 90 4294944912 [ 82.908929][ T5219] last result at 4294944913 [ 82.910948][ T5219] last redo_fd_request at 4294945389 [ 82.913325][ T5219] status=80 [ 82.914823][ T5219] fdc_busy=1 [ 82.916201][ T5219] floppy_work.func=floppy_work_workfn [ 82.918687][ T5219] cont=ffffffff8ad02f20 [ 82.920533][ T5219] current_req=0000000000000000 [ 82.922598][ T5219] command_status=-1 [ 82.924353][ T5219] [ 82.925472][ T5219] floppy1: floppy timeout called [ 82.940413][ T5567] floppy1: reschedule timeout lock fdc [ 82.940451][ T5219] [ 82.942755][ T5567] floppy1: setting NEWCHANGE in poll_drive [ 82.944097][ T5219] floppy driver state [ 82.948062][ T5219] ------------------- [ 82.949703][ T5219] now=4294945399 last interrupt=4294944912 diff=487 last called handler=main_command_interrupt [ 82.954290][ T5219] timeout_message=lock fdc [ 82.956206][ T5219] last output bytes: [ 82.957785][ T5219] 0 90 4294944909 [ 82.959090][ T5219] 1 90 4294944909 [ 82.960399][ T5219] 2 90 4294944909 [ 82.961743][ T5219] 12 90 4294944909 [ 82.963082][ T5219] 1b 90 4294944909 [ 82.964533][ T5219] ff 90 4294944909 [ 82.965928][ T5219] 12 80 4294944912 [ 82.967577][ T5219] 3 90 4294944912 [ 82.969272][ T5219] 3 80 4294944912 [ 82.970912][ T5219] a1 90 4294944912 [ 82.972529][ T5219] 1e 90 4294944912 [ 82.974250][ T5219] e6 80 4294944912 [ 82.975892][ T5219] 1 90 4294944912 [ 82.977382][ T5219] 0 90 4294944912 [ 82.978713][ T5219] 0 90 4294944912 [ 82.980101][ T5219] 1 90 4294944912 [ 82.981653][ T5219] 2 90 4294944912 [ 82.983109][ T5219] 24 90 4294944912 [ 82.984646][ T5219] 1b 90 4294944912 [ 82.986084][ T5219] ff 90 4294944912 [ 82.987461][ T5219] last result at 4294944913 [ 82.989104][ T5219] last redo_fd_request at 4294945397 [ 82.992037][ T5219] status=80 [ 82.993526][ T5219] fdc_busy=1 [ 82.994911][ T5219] floppy_work.func=floppy_work_workfn [ 82.997250][ T5219] cont=ffffffff8ad02f20 [ 82.999074][ T5219] current_req=0000000000000000 [ 83.001149][ T5219] command_status=-1 [ 83.002853][ T5219] [ 83.004074][ T5219] floppy1: floppy timeout called [ 83.019981][ T5570] floppy1: reschedule timeout lock fdc [ 83.020126][ T5219] [ 83.022253][ T5570] floppy1: setting NEWCHANGE in poll_drive [ 83.023253][ T5219] floppy driver state [ 83.027387][ T5219] ------------------- [ 83.029285][ T5219] now=4294945407 last interrupt=4294944912 diff=495 last called handler=main_command_interrupt [ 83.033912][ T5219] timeout_message=lock fdc [ 83.035850][ T5219] last output bytes: [ 83.037569][ T5219] 0 90 4294944909 [ 83.039027][ T5219] 1 90 4294944909 [ 83.040327][ T5219] 2 90 4294944909 [ 83.041658][ T5219] 12 90 4294944909 [ 83.042951][ T5219] 1b 90 4294944909 [ 83.044478][ T5219] ff 90 4294944909 [ 83.046207][ T5219] 12 80 4294944912 [ 83.047952][ T5219] 3 90 4294944912 [ 83.049670][ T5219] 3 80 4294944912 [ 83.051376][ T5219] a1 90 4294944912 [ 83.053024][ T5219] 1e 90 4294944912 [ 83.054729][ T5219] e6 80 4294944912 [ 83.056192][ T5219] 1 90 4294944912 [ 83.057636][ T5219] 0 90 4294944912 [ 83.059022][ T5219] 0 90 4294944912 [ 83.060355][ T5219] 1 90 4294944912 [ 83.062052][ T5219] 2 90 4294944912 [ 83.063749][ T5219] 24 90 4294944912 [ 83.065112][ T5219] 1b 90 4294944912 [ 83.066444][ T5219] ff 90 4294944912 [ 83.067776][ T5219] last result at 4294944913 [ 83.069390][ T5219] last redo_fd_request at 4294945405 [ 83.072634][ T5219] status=80 [ 83.074233][ T5219] fdc_busy=1 [ 83.075708][ T5219] floppy_work.func=floppy_work_workfn [ 83.078101][ T5219] cont=ffffffff8ad02f20 [ 83.079919][ T5219] current_req=0000000000000000 [ 83.082014][ T5219] command_status=-1 [ 83.083883][ T5219] [ 83.084999][ T5219] floppy1: floppy timeout called 2023/05/28 13:16:49 executed programs: 65 [ 83.099128][ T5573] floppy1: reschedule timeout lock fdc [ 83.099165][ T5219] [ 83.101568][ T5573] floppy1: setting NEWCHANGE in poll_drive [ 83.102503][ T5219] floppy driver state [ 83.106996][ T5219] ------------------- [ 83.108815][ T5219] now=4294945415 last interrupt=4294944912 diff=503 last called handler=main_command_interrupt [ 83.113222][ T5219] timeout_message=lock fdc [ 83.115242][ T5219] last output bytes: [ 83.116917][ T5219] 0 90 4294944909 [ 83.118554][ T5219] 1 90 4294944909 [ 83.120166][ T5219] 2 90 4294944909 [ 83.121875][ T5219] 12 90 4294944909 [ 83.123770][ T5219] 1b 90 4294944909 [ 83.125446][ T5219] ff 90 4294944909 [ 83.127115][ T5219] 12 80 4294944912 [ 83.128767][ T5219] 3 90 4294944912 [ 83.130425][ T5219] 3 80 4294944912 [ 83.132044][ T5219] a1 90 4294944912 [ 83.133746][ T5219] 1e 90 4294944912 [ 83.135343][ T5219] e6 80 4294944912 [ 83.136886][ T5219] 1 90 4294944912 [ 83.138546][ T5219] 0 90 4294944912 [ 83.140267][ T5219] 0 90 4294944912 [ 83.142009][ T5219] 1 90 4294944912 [ 83.143896][ T5219] 2 90 4294944912 [ 83.145661][ T5219] 24 90 4294944912 [ 83.147424][ T5219] 1b 90 4294944912 [ 83.149317][ T5219] ff 90 4294944912 [ 83.151046][ T5219] last result at 4294944913 [ 83.153056][ T5219] last redo_fd_request at 4294945413 [ 83.155438][ T5219] status=80 [ 83.156821][ T5219] fdc_busy=1 [ 83.158291][ T5219] floppy_work.func=floppy_work_workfn [ 83.160677][ T5219] cont=ffffffff8ad02f20 [ 83.162522][ T5219] current_req=0000000000000000 [ 83.164744][ T5219] command_status=-1 [ 83.166454][ T5219] [ 83.167542][ T5219] floppy1: floppy timeout called [ 83.185200][ T5576] floppy1: reschedule timeout lock fdc [ 83.187492][ T5576] floppy1: setting NEWCHANGE in poll_drive [ 83.189673][ T5219] [ 83.191340][ T5219] floppy driver state [ 83.193146][ T5219] ------------------- [ 83.195574][ T5219] now=4294945424 last interrupt=4294944912 diff=512 last called handler=main_command_interrupt [ 83.199984][ T5219] timeout_message=lock fdc [ 83.201648][ T5219] last output bytes: [ 83.203093][ T5219] 0 90 4294944909 [ 83.204637][ T5219] 1 90 4294944909 [ 83.205997][ T5219] 2 90 4294944909 [ 83.207399][ T5219] 12 90 4294944909 [ 83.208864][ T5219] 1b 90 4294944909 [ 83.210212][ T5219] ff 90 4294944909 [ 83.211654][ T5219] 12 80 4294944912 [ 83.213297][ T5219] 3 90 4294944912 [ 83.215055][ T5219] 3 80 4294944912 [ 83.216688][ T5219] a1 90 4294944912 [ 83.218338][ T5219] 1e 90 4294944912 [ 83.219960][ T5219] e6 80 4294944912 [ 83.221591][ T5219] 1 90 4294944912 [ 83.223038][ T5219] 0 90 4294944912 [ 83.224803][ T5219] 0 90 4294944912 [ 83.226249][ T5219] 1 90 4294944912 [ 83.227622][ T5219] 2 90 4294944912 [ 83.228988][ T5219] 24 90 4294944912 [ 83.230210][ T5219] 1b 90 4294944912 [ 83.231404][ T5219] ff 90 4294944912 [ 83.232637][ T5219] last result at 4294944913 [ 83.234382][ T5219] last redo_fd_request at 4294945421 [ 83.236374][ T5219] status=80 [ 83.237537][ T5219] fdc_busy=1 [ 83.238834][ T5219] floppy_work.func=floppy_work_workfn [ 83.240889][ T5219] cont=ffffffff8ad02f20 [ 83.242675][ T5219] current_req=0000000000000000 [ 83.244907][ T5219] command_status=-1 [ 83.246594][ T5219] [ 83.247711][ T5219] floppy1: floppy timeout called [ 83.270344][ T5579] floppy1: reschedule timeout lock fdc [ 83.271257][ T5219] [ 83.272777][ T5579] floppy1: setting NEWCHANGE in poll_drive [ 83.276493][ T5219] floppy driver state [ 83.278212][ T5219] ------------------- [ 83.279961][ T5219] now=4294945432 last interrupt=4294944912 diff=520 last called handler=main_command_interrupt [ 83.284473][ T5219] timeout_message=lock fdc [ 83.286449][ T5219] last output bytes: [ 83.288317][ T5219] 0 90 4294944909 [ 83.289870][ T5219] 1 90 4294944909 [ 83.291188][ T5219] 2 90 4294944909 [ 83.292514][ T5219] 12 90 4294944909 [ 83.293913][ T5219] 1b 90 4294944909 [ 83.295220][ T5219] ff 90 4294944909 [ 83.296542][ T5219] 12 80 4294944912 [ 83.297894][ T5219] 3 90 4294944912 [ 83.299233][ T5219] 3 80 4294944912 [ 83.300572][ T5219] a1 90 4294944912 [ 83.301943][ T5219] 1e 90 4294944912 [ 83.303276][ T5219] e6 80 4294944912 [ 83.304673][ T5219] 1 90 4294944912 [ 83.306000][ T5219] 0 90 4294944912 [ 83.307318][ T5219] 0 90 4294944912 [ 83.308604][ T5219] 1 90 4294944912 [ 83.309905][ T5219] 2 90 4294944912 [ 83.311200][ T5219] 24 90 4294944912 [ 83.312583][ T5219] 1b 90 4294944912 [ 83.314022][ T5219] ff 90 4294944912 [ 83.315304][ T5219] last result at 4294944913 [ 83.316854][ T5219] last redo_fd_request at 4294945429 [ 83.318686][ T5219] status=80 [ 83.319787][ T5219] fdc_busy=1 [ 83.320931][ T5219] floppy_work.func=floppy_work_workfn [ 83.322822][ T5219] cont=ffffffff8ad02f20 [ 83.324490][ T5219] current_req=0000000000000000 [ 83.326646][ T5219] command_status=-1 [ 83.328337][ T5219] [ 83.329465][ T5219] floppy1: floppy timeout called [ 83.344623][ T5582] floppy1: reschedule timeout lock fdc [ 83.344992][ T5219] [ 83.347193][ T5582] floppy1: setting NEWCHANGE in poll_drive [ 83.348033][ T5219] floppy driver state [ 83.348039][ T5219] ------------------- [ 83.353712][ T5219] now=4294945440 last interrupt=4294944912 diff=528 last called handler=main_command_interrupt [ 83.357542][ T5219] timeout_message=lock fdc [ 83.359088][ T5219] last output bytes: [ 83.360460][ T5219] 0 90 4294944909 [ 83.361782][ T5219] 1 90 4294944909 [ 83.363276][ T5219] 2 90 4294944909 [ 83.365446][ T5219] 12 90 4294944909 [ 83.366763][ T5219] 1b 90 4294944909 [ 83.368073][ T5219] ff 90 4294944909 [ 83.369385][ T5219] 12 80 4294944912 [ 83.370689][ T5219] 3 90 4294944912 [ 83.372012][ T5219] 3 80 4294944912 [ 83.373612][ T5219] a1 90 4294944912 [ 83.375042][ T5219] 1e 90 4294944912 [ 83.376367][ T5219] e6 80 4294944912 [ 83.377723][ T5219] 1 90 4294944912 [ 83.379044][ T5219] 0 90 4294944912 [ 83.380379][ T5219] 0 90 4294944912 [ 83.381722][ T5219] 1 90 4294944912 [ 83.383106][ T5219] 2 90 4294944912 [ 83.384573][ T5219] 24 90 4294944912 [ 83.386001][ T5219] 1b 90 4294944912 [ 83.387507][ T5219] ff 90 4294944912 [ 83.389050][ T5219] last result at 4294944913 [ 83.390691][ T5219] last redo_fd_request at 4294945437 [ 83.392649][ T5219] status=80 [ 83.393927][ T5219] fdc_busy=1 [ 83.395097][ T5219] floppy_work.func=floppy_work_workfn [ 83.397010][ T5219] cont=ffffffff8ad02f20 [ 83.398543][ T5219] current_req=0000000000000000 [ 83.400199][ T5219] command_status=-1 [ 83.401569][ T5219] [ 83.402461][ T5219] floppy1: floppy timeout called [ 83.418046][ T5585] floppy1: reschedule timeout lock fdc [ 83.418086][ T5219] [ 83.420479][ T5585] floppy1: setting NEWCHANGE in poll_drive [ 83.421224][ T5219] floppy driver state [ 83.421228][ T5219] ------------------- [ 83.426862][ T5219] now=4294945447 last interrupt=4294944912 diff=535 last called handler=main_command_interrupt [ 83.430544][ T5219] timeout_message=lock fdc [ 83.432091][ T5219] last output bytes: [ 83.433885][ T5219] 0 90 4294944909 [ 83.435484][ T5219] 1 90 4294944909 [ 83.437156][ T5219] 2 90 4294944909 [ 83.438816][ T5219] 12 90 4294944909 [ 83.440439][ T5219] 1b 90 4294944909 [ 83.442085][ T5219] ff 90 4294944909 [ 83.443828][ T5219] 12 80 4294944912 [ 83.445441][ T5219] 3 90 4294944912 [ 83.447078][ T5219] 3 80 4294944912 [ 83.448635][ T5219] a1 90 4294944912 [ 83.450165][ T5219] 1e 90 4294944912 [ 83.451792][ T5219] e6 80 4294944912 [ 83.453551][ T5219] 1 90 4294944912 [ 83.455242][ T5219] 0 90 4294944912 [ 83.456903][ T5219] 0 90 4294944912 [ 83.458597][ T5219] 1 90 4294944912 [ 83.460255][ T5219] 2 90 4294944912 [ 83.461868][ T5219] 24 90 4294944912 [ 83.463587][ T5219] 1b 90 4294944912 [ 83.465223][ T5219] ff 90 4294944912 [ 83.466861][ T5219] last result at 4294944913 [ 83.468885][ T5219] last redo_fd_request at 4294945445 [ 83.471255][ T5219] status=80 [ 83.472566][ T5219] fdc_busy=1 [ 83.474253][ T5219] floppy_work.func=floppy_work_workfn [ 83.476614][ T5219] cont=ffffffff8ad02f20 [ 83.478328][ T5219] current_req=0000000000000000 [ 83.480138][ T5219] command_status=-1 [ 83.481614][ T5219] [ 83.482529][ T5219] floppy1: floppy timeout called [ 83.500248][ T5588] floppy1: reschedule timeout lock fdc [ 83.500279][ T5219] [ 83.502621][ T5588] floppy1: setting NEWCHANGE in poll_drive [ 83.503569][ T5219] floppy driver state [ 83.503574][ T5219] ------------------- [ 83.503577][ T5219] now=4294945455 last interrupt=4294944912 diff=543 last called handler=main_command_interrupt [ 83.503594][ T5219] timeout_message=lock fdc [ 83.503597][ T5219] last output bytes: [ 83.516278][ T5219] 0 90 4294944909 [ 83.517670][ T5219] 1 90 4294944909 [ 83.519050][ T5219] 2 90 4294944909 [ 83.520405][ T5219] 12 90 4294944909 [ 83.521838][ T5219] 1b 90 4294944909 [ 83.523184][ T5219] ff 90 4294944909 [ 83.524681][ T5219] 12 80 4294944912 [ 83.526059][ T5219] 3 90 4294944912 [ 83.527382][ T5219] 3 80 4294944912 [ 83.528783][ T5219] a1 90 4294944912 [ 83.530183][ T5219] 1e 90 4294944912 [ 83.531824][ T5219] e6 80 4294944912 [ 83.533575][ T5219] 1 90 4294944912 [ 83.535177][ T5219] 0 90 4294944912 [ 83.536527][ T5219] 0 90 4294944912 [ 83.537888][ T5219] 1 90 4294944912 [ 83.539229][ T5219] 2 90 4294944912 [ 83.540619][ T5219] 24 90 4294944912 [ 83.542039][ T5219] 1b 90 4294944912 [ 83.543524][ T5219] ff 90 4294944912 [ 83.544908][ T5219] last result at 4294944913 [ 83.546683][ T5219] last redo_fd_request at 4294945453 [ 83.548539][ T5219] status=80 [ 83.549803][ T5219] fdc_busy=1 [ 83.551165][ T5219] floppy_work.func=floppy_work_workfn [ 83.553579][ T5219] cont=ffffffff8ad02f20 [ 83.555163][ T5219] current_req=0000000000000000 [ 83.556911][ T5219] command_status=-1 [ 83.558280][ T5219] [ 83.559192][ T5219] floppy1: floppy timeout called [ 83.581838][ T5591] floppy1: reschedule timeout lock fdc [ 83.581876][ T5219] [ 83.584405][ T5591] floppy1: setting NEWCHANGE in poll_drive [ 83.585338][ T5219] floppy driver state [ 83.585345][ T5219] ------------------- [ 83.591206][ T5219] now=4294945463 last interrupt=4294944912 diff=551 last called handler=main_command_interrupt [ 83.595804][ T5219] timeout_message=lock fdc [ 83.597868][ T5219] last output bytes: [ 83.599621][ T5219] 0 90 4294944909 [ 83.601297][ T5219] 1 90 4294944909 [ 83.602901][ T5219] 2 90 4294944909 [ 83.604664][ T5219] 12 90 4294944909 [ 83.606302][ T5219] 1b 90 4294944909 [ 83.607892][ T5219] ff 90 4294944909 [ 83.609563][ T5219] 12 80 4294944912 [ 83.611218][ T5219] 3 90 4294944912 [ 83.612885][ T5219] 3 80 4294944912 [ 83.614613][ T5219] a1 90 4294944912 [ 83.616251][ T5219] 1e 90 4294944912 [ 83.617943][ T5219] e6 80 4294944912 [ 83.619631][ T5219] 1 90 4294944912 [ 83.621283][ T5219] 0 90 4294944912 [ 83.622950][ T5219] 0 90 4294944912 [ 83.624756][ T5219] 1 90 4294944912 [ 83.626410][ T5219] 2 90 4294944912 [ 83.628097][ T5219] 24 90 4294944912 [ 83.629766][ T5219] 1b 90 4294944912 [ 83.631450][ T5219] ff 90 4294944912 [ 83.633412][ T5219] last result at 4294944913 [ 83.635533][ T5219] last redo_fd_request at 4294945460 [ 83.637915][ T5219] status=80 [ 83.639360][ T5219] fdc_busy=1 [ 83.640821][ T5219] floppy_work.func=floppy_work_workfn [ 83.643141][ T5219] cont=ffffffff8ad02f20 [ 83.645032][ T5219] current_req=0000000000000000 [ 83.647104][ T5219] command_status=-1 [ 83.648752][ T5219] [ 83.649831][ T5219] floppy1: floppy timeout called [ 83.670908][ T5594] floppy1: reschedule timeout lock fdc [ 83.670952][ T5219] [ 83.673051][ T5594] floppy1: setting NEWCHANGE in poll_drive [ 83.677146][ T5219] floppy driver state [ 83.678731][ T5219] ------------------- [ 83.680174][ T5219] now=4294945472 last interrupt=4294944912 diff=560 last called handler=main_command_interrupt [ 83.684173][ T5219] timeout_message=lock fdc [ 83.685798][ T5219] last output bytes: [ 83.687248][ T5219] 0 90 4294944909 [ 83.688595][ T5219] 1 90 4294944909 [ 83.689933][ T5219] 2 90 4294944909 [ 83.691272][ T5219] 12 90 4294944909 [ 83.692642][ T5219] 1b 90 4294944909 [ 83.695485][ T5219] ff 90 4294944909 [ 83.697136][ T5219] 12 80 4294944912 [ 83.698519][ T5219] 3 90 4294944912 [ 83.700070][ T5219] 3 80 4294944912 [ 83.701639][ T5219] a1 90 4294944912 [ 83.703280][ T5219] 1e 90 4294944912 [ 83.705155][ T5219] e6 80 4294944912 [ 83.706807][ T5219] 1 90 4294944912 [ 83.708336][ T5219] 0 90 4294944912 [ 83.709838][ T5219] 0 90 4294944912 [ 83.711343][ T5219] 1 90 4294944912 [ 83.712874][ T5219] 2 90 4294944912 [ 83.714816][ T5219] 24 90 4294944912 [ 83.716409][ T5219] 1b 90 4294944912 [ 83.718013][ T5219] ff 90 4294944912 [ 83.719600][ T5219] last result at 4294944913 [ 83.721539][ T5219] last redo_fd_request at 4294945469 [ 83.724171][ T5219] status=80 [ 83.725562][ T5219] fdc_busy=1 [ 83.726966][ T5219] floppy_work.func=floppy_work_workfn [ 83.729233][ T5219] cont=ffffffff8ad02f20 [ 83.730967][ T5219] current_req=0000000000000000 [ 83.732948][ T5219] command_status=-1 [ 83.734733][ T5219] [ 83.735897][ T5219] floppy1: floppy timeout called [ 83.751186][ T5597] floppy1: reschedule timeout lock fdc [ 83.753411][ T5597] floppy1: setting NEWCHANGE in poll_drive [ 83.753675][ T5219] [ 83.757002][ T5219] floppy driver state [ 83.758864][ T5219] ------------------- [ 83.760598][ T5219] now=4294945480 last interrupt=4294944912 diff=568 last called handler=main_command_interrupt [ 83.765295][ T5219] timeout_message=lock fdc [ 83.767129][ T5219] last output bytes: [ 83.768654][ T5219] 0 90 4294944909 [ 83.770254][ T5219] 1 90 4294944909 [ 83.771830][ T5219] 2 90 4294944909 [ 83.773391][ T5219] 12 90 4294944909 [ 83.774829][ T5219] 1b 90 4294944909 [ 83.776158][ T5219] ff 90 4294944909 [ 83.777539][ T5219] 12 80 4294944912 [ 83.778857][ T5219] 3 90 4294944912 [ 83.780171][ T5219] 3 80 4294944912 [ 83.781518][ T5219] a1 90 4294944912 [ 83.782846][ T5219] 1e 90 4294944912 [ 83.784260][ T5219] e6 80 4294944912 [ 83.785594][ T5219] 1 90 4294944912 [ 83.786995][ T5219] 0 90 4294944912 [ 83.788540][ T5219] 0 90 4294944912 [ 83.789875][ T5219] 1 90 4294944912 [ 83.791182][ T5219] 2 90 4294944912 [ 83.792759][ T5219] 24 90 4294944912 [ 83.794381][ T5219] 1b 90 4294944912 [ 83.795929][ T5219] ff 90 4294944912 [ 83.797394][ T5219] last result at 4294944913 [ 83.799183][ T5219] last redo_fd_request at 4294945478 [ 83.802375][ T5219] status=80 [ 83.804164][ T5219] fdc_busy=1 [ 83.805576][ T5219] floppy_work.func=floppy_work_workfn [ 83.807850][ T5219] cont=ffffffff8ad02f20 [ 83.809674][ T5219] current_req=0000000000000000 [ 83.811741][ T5219] command_status=-1 [ 83.813363][ T5219] [ 83.814569][ T5219] floppy1: floppy timeout called [ 83.829061][ T5600] floppy1: reschedule timeout lock fdc [ 83.829100][ T5219] [ 83.831719][ T5600] floppy1: setting NEWCHANGE in poll_drive [ 83.832810][ T5219] floppy driver state [ 83.837123][ T5219] ------------------- [ 83.838982][ T5219] now=4294945488 last interrupt=4294944912 diff=576 last called handler=main_command_interrupt [ 83.843594][ T5219] timeout_message=lock fdc [ 83.845609][ T5219] last output bytes: [ 83.847311][ T5219] 0 90 4294944909 [ 83.848934][ T5219] 1 90 4294944909 [ 83.850550][ T5219] 2 90 4294944909 [ 83.852127][ T5219] 12 90 4294944909 [ 83.853994][ T5219] 1b 90 4294944909 [ 83.855660][ T5219] ff 90 4294944909 [ 83.857257][ T5219] 12 80 4294944912 [ 83.858936][ T5219] 3 90 4294944912 [ 83.860594][ T5219] 3 80 4294944912 [ 83.862283][ T5219] a1 90 4294944912 [ 83.864076][ T5219] 1e 90 4294944912 [ 83.865781][ T5219] e6 80 4294944912 [ 83.867424][ T5219] 1 90 4294944912 [ 83.868758][ T5219] 0 90 4294944912 [ 83.870097][ T5219] 0 90 4294944912 [ 83.871416][ T5219] 1 90 4294944912 [ 83.872740][ T5219] 2 90 4294944912 [ 83.874434][ T5219] 24 90 4294944912 [ 83.876032][ T5219] 1b 90 4294944912 [ 83.877580][ T5219] ff 90 4294944912 [ 83.879169][ T5219] last result at 4294944913 [ 83.881093][ T5219] last redo_fd_request at 4294945486 [ 83.883105][ T5219] status=80 [ 83.884337][ T5219] fdc_busy=1 [ 83.885500][ T5219] floppy_work.func=floppy_work_workfn [ 83.887710][ T5219] cont=ffffffff8ad02f20 [ 83.889494][ T5219] current_req=0000000000000000 [ 83.891386][ T5219] command_status=-1 [ 83.892711][ T5219] [ 83.893705][ T5219] floppy1: floppy timeout called [ 83.910012][ T5603] floppy1: reschedule timeout lock fdc [ 83.911301][ T5219] [ 83.912412][ T5603] floppy1: setting NEWCHANGE in poll_drive [ 83.913372][ T5219] floppy driver state [ 83.913378][ T5219] ------------------- [ 83.919394][ T5219] now=4294945496 last interrupt=4294944912 diff=584 last called handler=main_command_interrupt [ 83.923784][ T5219] timeout_message=lock fdc [ 83.925544][ T5219] last output bytes: [ 83.927143][ T5219] 0 90 4294944909 [ 83.928754][ T5219] 1 90 4294944909 [ 83.930443][ T5219] 2 90 4294944909 [ 83.931928][ T5219] 12 90 4294944909 [ 83.933297][ T5219] 1b 90 4294944909 [ 83.934930][ T5219] ff 90 4294944909 [ 83.936850][ T5219] 12 80 4294944912 [ 83.938704][ T5219] 3 90 4294944912 [ 83.940516][ T5219] 3 80 4294944912 [ 83.942391][ T5219] a1 90 4294944912 [ 83.944142][ T5219] 1e 90 4294944912 [ 83.945904][ T5219] e6 80 4294944912 [ 83.947721][ T5219] 1 90 4294944912 [ 83.949500][ T5219] 0 90 4294944912 [ 83.951143][ T5219] 0 90 4294944912 [ 83.952791][ T5219] 1 90 4294944912 [ 83.954641][ T5219] 2 90 4294944912 [ 83.956430][ T5219] 24 90 4294944912 [ 83.958247][ T5219] 1b 90 4294944912 [ 83.960042][ T5219] ff 90 4294944912 [ 83.961811][ T5219] last result at 4294944913 [ 83.964130][ T5219] last redo_fd_request at 4294945494 [ 83.966497][ T5219] status=80 [ 83.967961][ T5219] fdc_busy=1 [ 83.969467][ T5219] floppy_work.func=floppy_work_workfn [ 83.971915][ T5219] cont=ffffffff8ad02f20 [ 83.973864][ T5219] current_req=0000000000000000 [ 83.975976][ T5219] command_status=-1 [ 83.977577][ T5219] [ 83.978573][ T5219] floppy1: floppy timeout called [ 84.001897][ T5606] floppy1: reschedule timeout lock fdc [ 84.001968][ T5219] [ 84.004641][ T5606] floppy1: setting NEWCHANGE in poll_drive [ 84.005370][ T5219] floppy driver state [ 84.009701][ T5219] ------------------- [ 84.011460][ T5219] now=4294945505 last interrupt=4294944912 diff=593 last called handler=main_command_interrupt [ 84.016144][ T5219] timeout_message=lock fdc [ 84.018165][ T5219] last output bytes: [ 84.019893][ T5219] 0 90 4294944909 [ 84.021568][ T5219] 1 90 4294944909 [ 84.023226][ T5219] 2 90 4294944909 [ 84.025039][ T5219] 12 90 4294944909 [ 84.026678][ T5219] 1b 90 4294944909 [ 84.028349][ T5219] ff 90 4294944909 [ 84.030012][ T5219] 12 80 4294944912 [ 84.031687][ T5219] 3 90 4294944912 [ 84.033412][ T5219] 3 80 4294944912 [ 84.035203][ T5219] a1 90 4294944912 [ 84.036882][ T5219] 1e 90 4294944912 [ 84.038466][ T5219] e6 80 4294944912 [ 84.040071][ T5219] 1 90 4294944912 [ 84.041759][ T5219] 0 90 4294944912 [ 84.043786][ T5219] 0 90 4294944912 [ 84.045367][ T5219] 1 90 4294944912 [ 84.046765][ T5219] 2 90 4294944912 [ 84.048296][ T5219] 24 90 4294944912 [ 84.049761][ T5219] 1b 90 4294944912 [ 84.051062][ T5219] ff 90 4294944912 [ 84.052401][ T5219] last result at 4294944913 [ 84.054200][ T5219] last redo_fd_request at 4294945502 [ 84.056126][ T5219] status=80 [ 84.057379][ T5219] fdc_busy=1 [ 84.058652][ T5219] floppy_work.func=floppy_work_workfn [ 84.061103][ T5219] cont=ffffffff8ad02f20 [ 84.062970][ T5219] current_req=0000000000000000 [ 84.065274][ T5219] command_status=-1 [ 84.067001][ T5219] [ 84.068171][ T5219] floppy1: floppy timeout called [ 84.082690][ T5609] floppy1: reschedule timeout lock fdc [ 84.083588][ T5219] [ 84.085185][ T5609] floppy1: setting NEWCHANGE in poll_drive [ 84.085976][ T5219] floppy driver state [ 84.089933][ T5219] ------------------- [ 84.091444][ T5219] now=4294945513 last interrupt=4294944912 diff=601 last called handler=main_command_interrupt [ 84.095457][ T5219] timeout_message=lock fdc [ 84.097442][ T5219] last output bytes: [ 84.099177][ T5219] 0 90 4294944909 [ 84.100831][ T5219] 1 90 4294944909 [ 84.102348][ T5219] 2 90 4294944909 [ 84.103876][ T5219] 12 90 4294944909 [ 84.105400][ T5219] 1b 90 4294944909 [ 84.107006][ T5219] ff 90 4294944909 [ 84.108247][ T5219] 12 80 4294944912 [ 84.109707][ T5219] 3 90 4294944912 [ 84.111229][ T5219] 3 80 4294944912 [ 84.112835][ T5219] a1 90 4294944912 [ 84.114474][ T5219] 1e 90 4294944912 [ 84.115972][ T5219] e6 80 4294944912 [ 84.117538][ T5219] 1 90 4294944912 [ 84.119008][ T5219] 0 90 4294944912 [ 84.120476][ T5219] 0 90 4294944912 [ 84.121933][ T5219] 1 90 4294944912 [ 84.123536][ T5219] 2 90 4294944912 [ 84.125065][ T5219] 24 90 4294944912 [ 84.126597][ T5219] 1b 90 4294944912 [ 84.128094][ T5219] ff 90 4294944912 [ 84.129603][ T5219] last result at 4294944913 [ 84.131350][ T5219] last redo_fd_request at 4294945511 [ 84.134121][ T5219] status=80 [ 84.135569][ T5219] fdc_busy=1 [ 84.137050][ T5219] floppy_work.func=floppy_work_workfn [ 84.139428][ T5219] cont=ffffffff8ad02f20 [ 84.141288][ T5219] current_req=0000000000000000 [ 84.143552][ T5219] command_status=-1 [ 84.145775][ T5219] [ 84.146954][ T5219] floppy1: floppy timeout called [ 84.161716][ T5612] floppy1: reschedule timeout lock fdc [ 84.163582][ T5219] [ 84.164516][ T5612] floppy1: setting NEWCHANGE in poll_drive [ 84.165246][ T5219] floppy driver state [ 84.165252][ T5219] ------------------- [ 84.171418][ T5219] now=4294945521 last interrupt=4294944912 diff=609 last called handler=main_command_interrupt [ 84.175491][ T5219] timeout_message=lock fdc [ 84.177203][ T5219] last output bytes: [ 84.178662][ T5219] 0 90 4294944909 [ 84.180006][ T5219] 1 90 4294944909 [ 84.181497][ T5219] 2 90 4294944909 [ 84.183004][ T5219] 12 90 4294944909 [ 84.184563][ T5219] 1b 90 4294944909 [ 84.186138][ T5219] ff 90 4294944909 [ 84.187761][ T5219] 12 80 4294944912 [ 84.189458][ T5219] 3 90 4294944912 [ 84.191211][ T5219] 3 80 4294944912 [ 84.192927][ T5219] a1 90 4294944912 [ 84.194664][ T5219] 1e 90 4294944912 [ 84.196312][ T5219] e6 80 4294944912 [ 84.197991][ T5219] 1 90 4294944912 [ 84.199642][ T5219] 0 90 4294944912 [ 84.201289][ T5219] 0 90 4294944912 [ 84.202931][ T5219] 1 90 4294944912 [ 84.204650][ T5219] 2 90 4294944912 [ 84.206259][ T5219] 24 90 4294944912 [ 84.207914][ T5219] 1b 90 4294944912 [ 84.209526][ T5219] ff 90 4294944912 [ 84.211167][ T5219] last result at 4294944913 [ 84.214040][ T5219] last redo_fd_request at 4294945519 [ 84.216157][ T5219] status=80 [ 84.217490][ T5219] fdc_busy=1 [ 84.218790][ T5219] floppy_work.func=floppy_work_workfn [ 84.220846][ T5219] cont=ffffffff8ad02f20 [ 84.222611][ T5219] current_req=0000000000000000 [ 84.224697][ T5219] command_status=-1 [ 84.226068][ T5219] [ 84.226993][ T5219] floppy1: floppy timeout called [ 84.241348][ T5615] floppy1: reschedule timeout lock fdc [ 84.244109][ T5219] [ 84.245166][ T5219] floppy driver state [ 84.246901][ T5219] ------------------- [ 84.248877][ T5615] floppy1: setting NEWCHANGE in poll_drive [ 84.248949][ T5219] now=4294945529 last interrupt=4294944912 diff=617 last called handler=main_command_interrupt [ 84.255593][ T5219] timeout_message=lock fdc [ 84.257318][ T5219] last output bytes: [ 84.259049][ T5219] 0 90 4294944909 [ 84.260696][ T5219] 1 90 4294944909 [ 84.262325][ T5219] 2 90 4294944909 [ 84.263904][ T5219] 12 90 4294944909 [ 84.265524][ T5219] 1b 90 4294944909 [ 84.267119][ T5219] ff 90 4294944909 [ 84.268761][ T5219] 12 80 4294944912 [ 84.270410][ T5219] 3 90 4294944912 [ 84.272053][ T5219] 3 80 4294944912 [ 84.273891][ T5219] a1 90 4294944912 [ 84.275500][ T5219] 1e 90 4294944912 [ 84.277128][ T5219] e6 80 4294944912 [ 84.278762][ T5219] 1 90 4294944912 [ 84.280376][ T5219] 0 90 4294944912 [ 84.281982][ T5219] 0 90 4294944912 [ 84.283675][ T5219] 1 90 4294944912 [ 84.285281][ T5219] 2 90 4294944912 [ 84.286878][ T5219] 24 90 4294944912 [ 84.288486][ T5219] 1b 90 4294944912 [ 84.290091][ T5219] ff 90 4294944912 [ 84.291720][ T5219] last result at 4294944913 [ 84.293764][ T5219] last redo_fd_request at 4294945527 [ 84.296074][ T5219] status=80 [ 84.297481][ T5219] fdc_busy=1 [ 84.298883][ T5219] floppy_work.func=floppy_work_workfn [ 84.301163][ T5219] cont=ffffffff8ad02f20 [ 84.302967][ T5219] current_req=0000000000000000 [ 84.305103][ T5219] command_status=-1 [ 84.306740][ T5219] [ 84.307829][ T5219] floppy1: floppy timeout called [ 84.323675][ T5618] floppy1: reschedule timeout lock fdc [ 84.323711][ T5219] [ 84.325908][ T5618] floppy1: setting NEWCHANGE in poll_drive [ 84.326826][ T5219] floppy driver state [ 84.330398][ T5219] ------------------- [ 84.331882][ T5219] now=4294945537 last interrupt=4294944912 diff=625 last called handler=main_command_interrupt [ 84.335800][ T5219] timeout_message=lock fdc [ 84.337405][ T5219] last output bytes: [ 84.338801][ T5219] 0 90 4294944909 [ 84.340142][ T5219] 1 90 4294944909 [ 84.341743][ T5219] 2 90 4294944909 [ 84.343405][ T5219] 12 90 4294944909 [ 84.344855][ T5219] 1b 90 4294944909 [ 84.346474][ T5219] ff 90 4294944909 [ 84.348201][ T5219] 12 80 4294944912 [ 84.349925][ T5219] 3 90 4294944912 [ 84.351591][ T5219] 3 80 4294944912 [ 84.353244][ T5219] a1 90 4294944912 [ 84.354990][ T5219] 1e 90 4294944912 [ 84.356703][ T5219] e6 80 4294944912 [ 84.358396][ T5219] 1 90 4294944912 [ 84.360043][ T5219] 0 90 4294944912 [ 84.361683][ T5219] 0 90 4294944912 [ 84.363324][ T5219] 1 90 4294944912 [ 84.365084][ T5219] 2 90 4294944912 [ 84.366786][ T5219] 24 90 4294944912 [ 84.368428][ T5219] 1b 90 4294944912 [ 84.370117][ T5219] ff 90 4294944912 [ 84.371503][ T5219] last result at 4294944913 [ 84.373218][ T5219] last redo_fd_request at 4294945535 [ 84.375702][ T5219] status=80 [ 84.377058][ T5219] fdc_busy=1 [ 84.378230][ T5219] floppy_work.func=floppy_work_workfn [ 84.380300][ T5219] cont=ffffffff8ad02f20 [ 84.381971][ T5219] current_req=0000000000000000 [ 84.384032][ T5219] command_status=-1 [ 84.385503][ T5219] [ 84.386381][ T5219] floppy1: floppy timeout called [ 84.401647][ T5621] floppy1: reschedule timeout lock fdc [ 84.403535][ T5219] [ 84.404295][ T5621] floppy1: setting NEWCHANGE in poll_drive [ 84.404950][ T5219] floppy driver state [ 84.404956][ T5219] ------------------- [ 84.410633][ T5219] now=4294945545 last interrupt=4294944912 diff=633 last called handler=main_command_interrupt [ 84.414881][ T5219] timeout_message=lock fdc [ 84.416624][ T5219] last output bytes: [ 84.418256][ T5219] 0 90 4294944909 [ 84.419839][ T5219] 1 90 4294944909 [ 84.421441][ T5219] 2 90 4294944909 [ 84.423035][ T5219] 12 90 4294944909 [ 84.424577][ T5219] 1b 90 4294944909 [ 84.426017][ T5219] ff 90 4294944909 [ 84.427526][ T5219] 12 80 4294944912 [ 84.429198][ T5219] 3 90 4294944912 [ 84.430714][ T5219] 3 80 4294944912 [ 84.432122][ T5219] a1 90 4294944912 [ 84.433860][ T5219] 1e 90 4294944912 [ 84.435385][ T5219] e6 80 4294944912 [ 84.436888][ T5219] 1 90 4294944912 [ 84.438307][ T5219] 0 90 4294944912 [ 84.439713][ T5219] 0 90 4294944912 [ 84.441353][ T5219] 1 90 4294944912 [ 84.442777][ T5219] 2 90 4294944912 [ 84.444165][ T5219] 24 90 4294944912 [ 84.445605][ T5219] 1b 90 4294944912 [ 84.447074][ T5219] ff 90 4294944912 [ 84.448364][ T5219] last result at 4294944913 [ 84.449911][ T5219] last redo_fd_request at 4294945543 [ 84.452475][ T5219] status=80 [ 84.454006][ T5219] fdc_busy=1 [ 84.455132][ T5219] floppy_work.func=floppy_work_workfn [ 84.456987][ T5219] cont=ffffffff8ad02f20 [ 84.458431][ T5219] current_req=0000000000000000 [ 84.460079][ T5219] command_status=-1 [ 84.461428][ T5219] [ 84.462428][ T5219] floppy1: floppy timeout called [ 84.478457][ T5624] floppy1: reschedule timeout lock fdc [ 84.478500][ T5219] [ 84.480996][ T5624] floppy1: setting NEWCHANGE in poll_drive [ 84.481710][ T5219] floppy driver state [ 84.481714][ T5219] ------------------- [ 84.481717][ T5219] now=4294945552 last interrupt=4294944912 diff=640 last called handler=main_command_interrupt [ 84.492275][ T5219] timeout_message=lock fdc [ 84.494348][ T5219] last output bytes: [ 84.495928][ T5219] 0 90 4294944909 [ 84.497282][ T5219] 1 90 4294944909 [ 84.498992][ T5219] 2 90 4294944909 [ 84.500737][ T5219] 12 90 4294944909 [ 84.502340][ T5219] 1b 90 4294944909 [ 84.504533][ T5219] ff 90 4294944909 [ 84.505978][ T5219] 12 80 4294944912 [ 84.507403][ T5219] 3 90 4294944912 [ 84.508823][ T5219] 3 80 4294944912 [ 84.510224][ T5219] a1 90 4294944912 [ 84.511660][ T5219] 1e 90 4294944912 [ 84.513112][ T5219] e6 80 4294944912 [ 84.514644][ T5219] 1 90 4294944912 [ 84.516123][ T5219] 0 90 4294944912 [ 84.517496][ T5219] 0 90 4294944912 [ 84.518841][ T5219] 1 90 4294944912 [ 84.520225][ T5219] 2 90 4294944912 [ 84.521676][ T5219] 24 90 4294944912 [ 84.523257][ T5219] 1b 90 4294944912 [ 84.524769][ T5219] ff 90 4294944912 [ 84.526209][ T5219] last result at 4294944913 [ 84.527874][ T5219] last redo_fd_request at 4294945551 [ 84.529821][ T5219] status=80 [ 84.531106][ T5219] fdc_busy=1 [ 84.532372][ T5219] floppy_work.func=floppy_work_workfn [ 84.534435][ T5219] cont=ffffffff8ad02f20 [ 84.535977][ T5219] current_req=0000000000000000 [ 84.537699][ T5219] command_status=-1 [ 84.539158][ T5219] [ 84.540069][ T5219] floppy1: floppy timeout called [ 84.554941][ T5627] floppy1: reschedule timeout lock fdc [ 84.554972][ T10] [ 84.557353][ T5627] floppy1: setting NEWCHANGE in poll_drive [ 84.560970][ T10] floppy driver state [ 84.562703][ T10] ------------------- [ 84.564599][ T10] now=4294945561 last interrupt=4294944912 diff=649 last called handler=main_command_interrupt [ 84.569030][ T10] timeout_message=lock fdc [ 84.570985][ T10] last output bytes: [ 84.572751][ T10] 0 90 4294944909 [ 84.574696][ T10] 1 90 4294944909 [ 84.576317][ T10] 2 90 4294944909 [ 84.577939][ T10] 12 90 4294944909 [ 84.579620][ T10] 1b 90 4294944909 [ 84.581402][ T10] ff 90 4294944909 [ 84.583125][ T10] 12 80 4294944912 [ 84.585487][ T10] 3 90 4294944912 [ 84.587174][ T10] 3 80 4294944912 [ 84.588811][ T10] a1 90 4294944912 [ 84.590392][ T10] 1e 90 4294944912 [ 84.592037][ T10] e6 80 4294944912 [ 84.594292][ T10] 1 90 4294944912 [ 84.595837][ T10] 0 90 4294944912 [ 84.597195][ T10] 0 90 4294944912 [ 84.598519][ T10] 1 90 4294944912 [ 84.600075][ T10] 2 90 4294944912 [ 84.601665][ T10] 24 90 4294944912 [ 84.602989][ T10] 1b 90 4294944912 [ 84.604583][ T10] ff 90 4294944912 [ 84.606156][ T10] last result at 4294944913 [ 84.607967][ T10] last redo_fd_request at 4294945558 [ 84.610237][ T10] status=80 [ 84.611597][ T10] fdc_busy=1 [ 84.613031][ T10] floppy_work.func=floppy_work_workfn [ 84.615713][ T10] cont=ffffffff8ad02f20 [ 84.617180][ T10] current_req=0000000000000000 [ 84.619223][ T10] command_status=-1 [ 84.620904][ T10] [ 84.621999][ T10] floppy1: floppy timeout called [ 84.633205][ T5630] floppy1: reschedule timeout lock fdc [ 84.633265][ T10] [ 84.635265][ T5630] floppy1: setting NEWCHANGE in poll_drive [ 84.636915][ T10] floppy driver state [ 84.639822][ T10] ------------------- [ 84.641540][ T10] now=4294945568 last interrupt=4294944912 diff=656 last called handler=main_command_interrupt [ 84.645988][ T10] timeout_message=lock fdc [ 84.647948][ T10] last output bytes: [ 84.649698][ T10] 0 90 4294944909 [ 84.651385][ T10] 1 90 4294944909 [ 84.653104][ T10] 2 90 4294944909 [ 84.654957][ T10] 12 90 4294944909 [ 84.656668][ T10] 1b 90 4294944909 [ 84.658232][ T10] ff 90 4294944909 [ 84.659595][ T10] 12 80 4294944912 [ 84.660906][ T10] 3 90 4294944912 [ 84.662214][ T10] 3 80 4294944912 [ 84.663588][ T10] a1 90 4294944912 [ 84.664885][ T10] 1e 90 4294944912 [ 84.666130][ T10] e6 80 4294944912 [ 84.667435][ T10] 1 90 4294944912 [ 84.668777][ T10] 0 90 4294944912 [ 84.670088][ T10] 0 90 4294944912 [ 84.671686][ T10] 1 90 4294944912 [ 84.673322][ T10] 2 90 4294944912 [ 84.674938][ T10] 24 90 4294944912 [ 84.676521][ T10] 1b 90 4294944912 [ 84.678132][ T10] ff 90 4294944912 [ 84.679693][ T10] last result at 4294944913 [ 84.681396][ T10] last redo_fd_request at 4294945567 [ 84.683260][ T10] status=80 [ 84.684429][ T10] fdc_busy=1 [ 84.685555][ T10] floppy_work.func=floppy_work_workfn [ 84.687401][ T10] cont=ffffffff8ad02f20 [ 84.688810][ T10] current_req=0000000000000000 [ 84.690434][ T10] command_status=-1 [ 84.691742][ T10] [ 84.692608][ T10] floppy1: floppy timeout called [ 84.705578][ T5633] floppy1: reschedule timeout lock fdc [ 84.705638][ T10] [ 84.707589][ T5633] floppy1: setting NEWCHANGE in poll_drive [ 84.708309][ T10] floppy driver state [ 84.708314][ T10] ------------------- [ 84.713109][ T10] now=4294945575 last interrupt=4294944912 diff=663 last called handler=main_command_interrupt [ 84.716965][ T10] timeout_message=lock fdc [ 84.718563][ T10] last output bytes: [ 84.720029][ T10] 0 90 4294944909 [ 84.721622][ T10] 1 90 4294944909 [ 84.723200][ T10] 2 90 4294944909 [ 84.725518][ T10] 12 90 4294944909 [ 84.727176][ T10] 1b 90 4294944909 [ 84.728839][ T10] ff 90 4294944909 [ 84.730491][ T10] 12 80 4294944912 [ 84.732113][ T10] 3 90 4294944912 [ 84.733898][ T10] 3 80 4294944912 [ 84.735586][ T10] a1 90 4294944912 [ 84.737286][ T10] 1e 90 4294944912 [ 84.738960][ T10] e6 80 4294944912 [ 84.740607][ T10] 1 90 4294944912 [ 84.742262][ T10] 0 90 4294944912 [ 84.743966][ T10] 0 90 4294944912 [ 84.745611][ T10] 1 90 4294944912 [ 84.747238][ T10] 2 90 4294944912 [ 84.748899][ T10] 24 90 4294944912 [ 84.750512][ T10] 1b 90 4294944912 [ 84.752116][ T10] ff 90 4294944912 [ 84.753882][ T10] last result at 4294944913 [ 84.755825][ T10] last redo_fd_request at 4294945574 [ 84.758214][ T10] status=80 [ 84.759631][ T10] fdc_busy=1 [ 84.761087][ T10] floppy_work.func=floppy_work_workfn [ 84.763567][ T10] cont=ffffffff8ad02f20 [ 84.765413][ T10] current_req=0000000000000000 [ 84.767539][ T10] command_status=-1 [ 84.769247][ T10] [ 84.770312][ T10] floppy1: floppy timeout called [ 84.784873][ T5636] floppy1: reschedule timeout lock fdc [ 84.784954][ T10] [ 84.787007][ T5636] floppy1: setting NEWCHANGE in poll_drive [ 84.787950][ T10] floppy driver state [ 84.787957][ T10] ------------------- [ 84.794086][ T10] now=4294945584 last interrupt=4294944912 diff=672 last called handler=main_command_interrupt [ 84.798485][ T10] timeout_message=lock fdc [ 84.800399][ T10] last output bytes: [ 84.802141][ T10] 0 90 4294944909 [ 84.803943][ T10] 1 90 4294944909 [ 84.805740][ T10] 2 90 4294944909 [ 84.807454][ T10] 12 90 4294944909 [ 84.809180][ T10] 1b 90 4294944909 [ 84.810894][ T10] ff 90 4294944909 [ 84.812633][ T10] 12 80 4294944912 [ 84.814616][ T10] 3 90 4294944912 [ 84.816304][ T10] 3 80 4294944912 [ 84.818012][ T10] a1 90 4294944912 [ 84.819671][ T10] 1e 90 4294944912 [ 84.821299][ T10] e6 80 4294944912 [ 84.822982][ T10] 1 90 4294944912 [ 84.824561][ T10] 0 90 4294944912 [ 84.826133][ T10] 0 90 4294944912 [ 84.827436][ T10] 1 90 4294944912 [ 84.828741][ T10] 2 90 4294944912 [ 84.830026][ T10] 24 90 4294944912 [ 84.831393][ T10] 1b 90 4294944912 [ 84.832679][ T10] ff 90 4294944912 [ 84.834314][ T10] last result at 4294944913 [ 84.835960][ T10] last redo_fd_request at 4294945581 [ 84.838214][ T10] status=80 [ 84.839561][ T10] fdc_busy=1 [ 84.840960][ T10] floppy_work.func=floppy_work_workfn [ 84.843247][ T10] cont=ffffffff8ad02f20 [ 84.845158][ T10] current_req=0000000000000000 [ 84.847195][ T10] command_status=-1 [ 84.848844][ T10] [ 84.849919][ T10] floppy1: floppy timeout called [ 84.864760][ T5639] floppy1: reschedule timeout lock fdc [ 84.867001][ T5219] [ 84.867991][ T5219] floppy driver state [ 84.869673][ T5219] ------------------- [ 84.869772][ T5639] floppy1: setting NEWCHANGE in poll_drive [ 84.871313][ T5219] now=4294945591 last interrupt=4294944912 diff=679 last called handler=main_command_interrupt [ 84.877847][ T5219] timeout_message=lock fdc [ 84.879571][ T5219] last output bytes: [ 84.881233][ T5219] 0 90 4294944909 [ 84.882697][ T5219] 1 90 4294944909 [ 84.884353][ T5219] 2 90 4294944909 [ 84.885832][ T5219] 12 90 4294944909 [ 84.887380][ T5219] 1b 90 4294944909 [ 84.888914][ T5219] ff 90 4294944909 [ 84.890435][ T5219] 12 80 4294944912 [ 84.891800][ T5219] 3 90 4294944912 [ 84.893407][ T5219] 3 80 4294944912 [ 84.895289][ T5219] a1 90 4294944912 [ 84.896872][ T5219] 1e 90 4294944912 [ 84.898321][ T5219] e6 80 4294944912 [ 84.900022][ T5219] 1 90 4294944912 [ 84.901658][ T5219] 0 90 4294944912 [ 84.903274][ T5219] 0 90 4294944912 [ 84.904994][ T5219] 1 90 4294944912 [ 84.906316][ T5219] 2 90 4294944912 [ 84.907835][ T5219] 24 90 4294944912 [ 84.909293][ T5219] 1b 90 4294944912 [ 84.910657][ T5219] ff 90 4294944912 [ 84.912120][ T5219] last result at 4294944913 [ 84.914250][ T5219] last redo_fd_request at 4294945589 [ 84.917597][ T5219] status=80 [ 84.918930][ T5219] fdc_busy=1 [ 84.920069][ T5219] floppy_work.func=floppy_work_workfn [ 84.921952][ T5219] cont=ffffffff8ad02f20 [ 84.923418][ T5219] current_req=0000000000000000 [ 84.925149][ T5219] command_status=-1 [ 84.926534][ T5219] [ 84.927606][ T5219] floppy1: floppy timeout called [ 84.944656][ T5642] floppy1: reschedule timeout lock fdc [ 84.944680][ T5219] [ 84.946909][ T5642] floppy1: setting NEWCHANGE in poll_drive [ 84.947828][ T5219] floppy driver state [ 84.947833][ T5219] ------------------- [ 84.953105][ T5219] now=4294945599 last interrupt=4294944912 diff=687 last called handler=main_command_interrupt [ 84.957048][ T5219] timeout_message=lock fdc [ 84.958702][ T5219] last output bytes: [ 84.960159][ T5219] 0 90 4294944909 [ 84.961568][ T5219] 1 90 4294944909 [ 84.963044][ T5219] 2 90 4294944909 [ 84.964089][ T63] Bluetooth: hci0: command 0x0419 tx timeout [ 84.964907][ T5219] 12 90 4294944909 [ 84.968641][ T5219] 1b 90 4294944909 [ 84.969978][ T5219] ff 90 4294944909 [ 84.971553][ T5219] 12 80 4294944912 [ 84.973232][ T5219] 3 90 4294944912 [ 84.974804][ T5219] 3 80 4294944912 [ 84.976272][ T5219] a1 90 4294944912 [ 84.977714][ T5219] 1e 90 4294944912 [ 84.979213][ T5219] e6 80 4294944912 [ 84.980595][ T5219] 1 90 4294944912 [ 84.982048][ T5219] 0 90 4294944912 [ 84.983650][ T5219] 0 90 4294944912 [ 84.985024][ T5219] 1 90 4294944912 [ 84.986486][ T5219] 2 90 4294944912 [ 84.987968][ T5219] 24 90 4294944912 [ 84.989355][ T5219] 1b 90 4294944912 [ 84.990866][ T5219] ff 90 4294944912 [ 84.992362][ T5219] last result at 4294944913 [ 84.994155][ T5219] last redo_fd_request at 4294945597 [ 84.997200][ T5219] status=80 [ 84.998538][ T5219] fdc_busy=1 [ 84.999706][ T5219] floppy_work.func=floppy_work_workfn [ 85.001616][ T5219] cont=ffffffff8ad02f20 [ 85.003281][ T5219] current_req=0000000000000000 [ 85.005927][ T5219] command_status=-1 [ 85.007633][ T5219] [ 85.008715][ T5219] floppy1: floppy timeout called [ 85.027138][ T5645] floppy1: reschedule timeout lock fdc [ 85.027197][ T5219] [ 85.029580][ T5645] floppy1: setting NEWCHANGE in poll_drive [ 85.030638][ T5219] floppy driver state [ 85.035122][ T5219] ------------------- [ 85.036502][ T5219] now=4294945608 last interrupt=4294944912 diff=696 last called handler=main_command_interrupt [ 85.040490][ T5219] timeout_message=lock fdc [ 85.042296][ T5219] last output bytes: [ 85.044011][ T5219] 0 90 4294944909 [ 85.045547][ T5219] 1 90 4294944909 [ 85.047074][ T5219] 2 90 4294944909 [ 85.048606][ T5219] 12 90 4294944909 [ 85.050258][ T5219] 1b 90 4294944909 [ 85.051921][ T5219] ff 90 4294944909 [ 85.054147][ T5219] 12 80 4294944912 [ 85.055648][ T5219] 3 90 4294944912 [ 85.057236][ T5219] 3 80 4294944912 [ 85.058852][ T5219] a1 90 4294944912 [ 85.060459][ T5219] 1e 90 4294944912 [ 85.061876][ T5219] e6 80 4294944912 [ 85.063298][ T5219] 1 90 4294944912 [ 85.064900][ T5219] 0 90 4294944912 [ 85.066519][ T5219] 0 90 4294944912 [ 85.068086][ T5219] 1 90 4294944912 [ 85.069678][ T5219] 2 90 4294944912 [ 85.071245][ T5219] 24 90 4294944912 [ 85.072761][ T5219] 1b 90 4294944912 [ 85.074451][ T5219] ff 90 4294944912 [ 85.076062][ T5219] last result at 4294944913 [ 85.078040][ T5219] last redo_fd_request at 4294945605 [ 85.080385][ T5219] status=80 [ 85.081778][ T5219] fdc_busy=1 [ 85.083196][ T5219] floppy_work.func=floppy_work_workfn [ 85.085625][ T5219] cont=ffffffff8ad02f20 [ 85.087402][ T5219] current_req=0000000000000000 [ 85.089442][ T5219] command_status=-1 [ 85.091098][ T5219] [ 85.092164][ T5219] floppy1: floppy timeout called [ 85.105429][ T5648] floppy1: reschedule timeout lock fdc [ 85.107874][ T5648] floppy1: setting NEWCHANGE in poll_drive [ 85.110411][ T5219] [ 85.111457][ T5219] floppy driver state [ 85.113184][ T5219] ------------------- [ 85.115049][ T5219] now=4294945616 last interrupt=4294944912 diff=704 last called handler=main_command_interrupt [ 85.119178][ T5219] timeout_message=lock fdc [ 85.120891][ T5219] last output bytes: [ 85.122331][ T5219] 0 90 4294944909 [ 85.123928][ T5219] 1 90 4294944909 [ 85.125568][ T5219] 2 90 4294944909 [ 85.127190][ T5219] 12 90 4294944909 [ 85.128834][ T5219] 1b 90 4294944909 [ 85.130486][ T5219] ff 90 4294944909 [ 85.132153][ T5219] 12 80 4294944912 [ 85.133958][ T5219] 3 90 4294944912 [ 85.135657][ T5219] 3 80 4294944912 [ 85.137351][ T5219] a1 90 4294944912 [ 85.139025][ T5219] 1e 90 4294944912 [ 85.140721][ T5219] e6 80 4294944912 [ 85.142371][ T5219] 1 90 4294944912 [ 85.144116][ T5219] 0 90 4294944912 [ 85.145757][ T5219] 0 90 4294944912 [ 85.147371][ T5219] 1 90 4294944912 [ 85.148821][ T5219] 2 90 4294944912 [ 85.150391][ T5219] 24 90 4294944912 [ 85.151989][ T5219] 1b 90 4294944912 [ 85.153328][ T5219] ff 90 4294944912 [ 85.154722][ T5219] last result at 4294944913 [ 85.156337][ T5219] last redo_fd_request at 4294945614 [ 85.158209][ T5219] status=80 [ 85.159459][ T5219] fdc_busy=1 [ 85.160850][ T5219] floppy_work.func=floppy_work_workfn [ 85.163106][ T5219] cont=ffffffff8ad02f20 [ 85.165516][ T5219] current_req=0000000000000000 [ 85.167466][ T5219] command_status=-1 [ 85.169172][ T5219] [ 85.170270][ T5219] floppy1: floppy timeout called [ 85.189987][ T5651] floppy1: reschedule timeout lock fdc [ 85.190001][ T5219] [ 85.192330][ T5651] floppy1: setting NEWCHANGE in poll_drive [ 85.195878][ T5219] floppy driver state [ 85.197596][ T5219] ------------------- [ 85.199182][ T5219] now=4294945624 last interrupt=4294944912 diff=712 last called handler=main_command_interrupt [ 85.203180][ T5219] timeout_message=lock fdc [ 85.205085][ T5219] last output bytes: [ 85.206628][ T5219] 0 90 4294944909 [ 85.207955][ T5219] 1 90 4294944909 [ 85.209282][ T5219] 2 90 4294944909 [ 85.210673][ T5219] 12 90 4294944909 [ 85.212381][ T5219] 1b 90 4294944909 [ 85.214203][ T5219] ff 90 4294944909 [ 85.215920][ T5219] 12 80 4294944912 [ 85.217582][ T5219] 3 90 4294944912 [ 85.219234][ T5219] 3 80 4294944912 [ 85.220930][ T5219] a1 90 4294944912 [ 85.222605][ T5219] 1e 90 4294944912 [ 85.224561][ T5219] e6 80 4294944912 [ 85.225951][ T5219] 1 90 4294944912 [ 85.227465][ T5219] 0 90 4294944912 [ 85.228929][ T5219] 0 90 4294944912 [ 85.230538][ T5219] 1 90 4294944912 [ 85.231991][ T5219] 2 90 4294944912 [ 85.233649][ T5219] 24 90 4294944912 [ 85.235115][ T5219] 1b 90 4294944912 [ 85.236498][ T5219] ff 90 4294944912 [ 85.238070][ T5219] last result at 4294944913 [ 85.239873][ T5219] last redo_fd_request at 4294945621 [ 85.241964][ T5219] status=80 [ 85.243287][ T5219] fdc_busy=1 [ 85.244619][ T5219] floppy_work.func=floppy_work_workfn [ 85.246773][ T5219] cont=ffffffff8ad02f20 [ 85.248436][ T5219] current_req=0000000000000000 [ 85.250316][ T5219] command_status=-1 [ 85.251754][ T5219] [ 85.252857][ T5219] floppy1: floppy timeout called [ 85.269783][ T5654] floppy1: reschedule timeout lock fdc [ 85.269820][ T5219] [ 85.272038][ T5654] floppy1: setting NEWCHANGE in poll_drive [ 85.275381][ T5219] floppy driver state [ 85.276788][ T5219] ------------------- [ 85.278197][ T5219] now=4294945632 last interrupt=4294944912 diff=720 last called handler=main_command_interrupt [ 85.281744][ T5219] timeout_message=lock fdc [ 85.283403][ T5219] last output bytes: [ 85.285180][ T5219] 0 90 4294944909 [ 85.286508][ T5219] 1 90 4294944909 [ 85.288059][ T5219] 2 90 4294944909 [ 85.289470][ T5219] 12 90 4294944909 [ 85.290861][ T5219] 1b 90 4294944909 [ 85.292451][ T5219] ff 90 4294944909 [ 85.294253][ T5219] 12 80 4294944912 [ 85.295918][ T5219] 3 90 4294944912 [ 85.297341][ T5219] 3 80 4294944912 [ 85.298702][ T5219] a1 90 4294944912 [ 85.300238][ T5219] 1e 90 4294944912 [ 85.301833][ T5219] e6 80 4294944912 [ 85.303345][ T5219] 1 90 4294944912 [ 85.305040][ T5219] 0 90 4294944912 [ 85.306456][ T5219] 0 90 4294944912 [ 85.307728][ T5219] 1 90 4294944912 [ 85.309025][ T5219] 2 90 4294944912 [ 85.310299][ T5219] 24 90 4294944912 [ 85.311591][ T5219] 1b 90 4294944912 [ 85.312968][ T5219] ff 90 4294944912 [ 85.314412][ T5219] last result at 4294944913 [ 85.316029][ T5219] last redo_fd_request at 4294945630 [ 85.318083][ T5219] status=80 [ 85.319282][ T5219] fdc_busy=1 [ 85.321367][ T5219] floppy_work.func=floppy_work_workfn [ 85.323658][ T5219] cont=ffffffff8ad02f20 [ 85.325101][ T5219] current_req=0000000000000000 [ 85.326726][ T5219] command_status=-1 [ 85.328024][ T5219] [ 85.328897][ T5219] floppy1: floppy timeout called [ 85.350194][ T5657] floppy1: reschedule timeout lock fdc [ 85.350263][ T5219] [ 85.352419][ T5657] floppy1: setting NEWCHANGE in poll_drive [ 85.353292][ T5219] floppy driver state [ 85.353297][ T5219] ------------------- [ 85.353299][ T5219] now=4294945639 last interrupt=4294944912 diff=727 last called handler=main_command_interrupt [ 85.362662][ T5219] timeout_message=lock fdc [ 85.364487][ T5219] last output bytes: [ 85.365927][ T5219] 0 90 4294944909 [ 85.367239][ T5219] 1 90 4294944909 [ 85.368620][ T5219] 2 90 4294944909 [ 85.369968][ T5219] 12 90 4294944909 [ 85.371287][ T5219] 1b 90 4294944909 [ 85.372786][ T5219] ff 90 4294944909 [ 85.374145][ T5219] 12 80 4294944912 [ 85.375562][ T5219] 3 90 4294944912 [ 85.377031][ T5219] 3 80 4294944912 [ 85.378538][ T5219] a1 90 4294944912 [ 85.379964][ T5219] 1e 90 4294944912 [ 85.381583][ T5219] e6 80 4294944912 [ 85.383043][ T5219] 1 90 4294944912 [ 85.384771][ T5219] 0 90 4294944912 [ 85.386458][ T5219] 0 90 4294944912 [ 85.388106][ T5219] 1 90 4294944912 [ 85.389483][ T5219] 2 90 4294944912 [ 85.390804][ T5219] 24 90 4294944912 [ 85.392136][ T5219] 1b 90 4294944912 [ 85.393524][ T5219] ff 90 4294944912 [ 85.394833][ T5219] last result at 4294944913 [ 85.396407][ T5219] last redo_fd_request at 4294945637 [ 85.398264][ T5219] status=80 [ 85.399389][ T5219] fdc_busy=1 [ 85.400536][ T5219] floppy_work.func=floppy_work_workfn [ 85.402444][ T5219] cont=ffffffff8ad02f20 [ 85.404094][ T5219] current_req=0000000000000000 [ 85.405824][ T5219] command_status=-1 [ 85.407429][ T5219] [ 85.408512][ T5219] floppy1: floppy timeout called [ 85.424285][ T5660] floppy1: reschedule timeout lock fdc [ 85.425912][ T4922] [ 85.426818][ T5660] floppy1: setting NEWCHANGE in poll_drive [ 85.427761][ T4922] floppy driver state [ 85.427768][ T4922] ------------------- [ 85.434043][ T4922] now=4294945648 last interrupt=4294944912 diff=736 last called handler=main_command_interrupt [ 85.438503][ T4922] timeout_message=lock fdc [ 85.440453][ T4922] last output bytes: [ 85.442124][ T4922] 0 90 4294944909 [ 85.444230][ T4922] 1 90 4294944909 [ 85.445945][ T4922] 2 90 4294944909 [ 85.447665][ T4922] 12 90 4294944909 [ 85.449394][ T4922] 1b 90 4294944909 [ 85.451104][ T4922] ff 90 4294944909 [ 85.452811][ T4922] 12 80 4294944912 [ 85.454704][ T4922] 3 90 4294944912 [ 85.456407][ T4922] 3 80 4294944912 [ 85.458162][ T4922] a1 90 4294944912 [ 85.459833][ T4922] 1e 90 4294944912 [ 85.461525][ T4922] e6 80 4294944912 [ 85.463159][ T4922] 1 90 4294944912 [ 85.465035][ T4922] 0 90 4294944912 [ 85.466662][ T4922] 0 90 4294944912 [ 85.468324][ T4922] 1 90 4294944912 [ 85.469961][ T4922] 2 90 4294944912 [ 85.471622][ T4922] 24 90 4294944912 [ 85.473294][ T4922] 1b 90 4294944912 [ 85.475264][ T4922] ff 90 4294944912 [ 85.476899][ T4922] last result at 4294944913 [ 85.478863][ T4922] last redo_fd_request at 4294945645 [ 85.481132][ T4922] status=80 [ 85.482509][ T4922] fdc_busy=1 [ 85.484201][ T4922] floppy_work.func=floppy_work_workfn [ 85.486544][ T4922] cont=ffffffff8ad02f20 [ 85.488292][ T4922] current_req=0000000000000000 [ 85.490331][ T4922] command_status=-1 [ 85.491959][ T4922] [ 85.493048][ T4922] floppy1: floppy timeout called [ 85.507636][ T5663] floppy1: reschedule timeout lock fdc [ 85.510142][ T5663] floppy1: setting NEWCHANGE in poll_drive [ 85.512809][ T4922] [ 85.513945][ T4922] floppy driver state [ 85.515354][ T4922] ------------------- [ 85.516769][ T4922] now=4294945656 last interrupt=4294944912 diff=744 last called handler=main_command_interrupt [ 85.520334][ T4922] timeout_message=lock fdc [ 85.521923][ T4922] last output bytes: [ 85.523327][ T4922] 0 90 4294944909 [ 85.524941][ T4922] 1 90 4294944909 [ 85.526276][ T4922] 2 90 4294944909 [ 85.527574][ T4922] 12 90 4294944909 [ 85.528890][ T4922] 1b 90 4294944909 [ 85.530207][ T4922] ff 90 4294944909 [ 85.531525][ T4922] 12 80 4294944912 [ 85.532852][ T4922] 3 90 4294944912 [ 85.534248][ T4922] 3 80 4294944912 [ 85.535551][ T4922] a1 90 4294944912 [ 85.536884][ T4922] 1e 90 4294944912 [ 85.538191][ T4922] e6 80 4294944912 [ 85.539517][ T4922] 1 90 4294944912 [ 85.540827][ T4922] 0 90 4294944912 [ 85.542137][ T4922] 0 90 4294944912 [ 85.543564][ T4922] 1 90 4294944912 [ 85.544892][ T4922] 2 90 4294944912 [ 85.546215][ T4922] 24 90 4294944912 [ 85.547538][ T4922] 1b 90 4294944912 [ 85.548858][ T4922] ff 90 4294944912 [ 85.550184][ T4922] last result at 4294944913 [ 85.551954][ T4922] last redo_fd_request at 4294945654 [ 85.554084][ T4922] status=80 [ 85.555205][ T4922] fdc_busy=1 [ 85.556360][ T4922] floppy_work.func=floppy_work_workfn [ 85.558440][ T4922] cont=ffffffff8ad02f20 [ 85.559943][ T4922] current_req=0000000000000000 [ 85.561892][ T4922] command_status=-1 [ 85.563663][ T4922] [ 85.564561][ T4922] floppy1: floppy timeout called [ 85.587244][ T5666] floppy1: reschedule timeout lock fdc [ 85.587510][ T10] [ 85.589237][ T5666] floppy1: setting NEWCHANGE in poll_drive [ 85.590267][ T10] floppy driver state [ 85.594087][ T10] ------------------- [ 85.595886][ T10] now=4294945664 last interrupt=4294944912 diff=752 last called handler=main_command_interrupt [ 85.600672][ T10] timeout_message=lock fdc [ 85.602491][ T10] last output bytes: [ 85.604290][ T10] 0 90 4294944909 [ 85.605812][ T10] 1 90 4294944909 [ 85.607387][ T10] 2 90 4294944909 [ 85.608934][ T10] 12 90 4294944909 [ 85.610500][ T10] 1b 90 4294944909 [ 85.612012][ T10] ff 90 4294944909 [ 85.613744][ T10] 12 80 4294944912 [ 85.615402][ T10] 3 90 4294944912 [ 85.617065][ T10] 3 80 4294944912 [ 85.618758][ T10] a1 90 4294944912 [ 85.620398][ T10] 1e 90 4294944912 [ 85.622037][ T10] e6 80 4294944912 [ 85.624064][ T10] 1 90 4294944912 [ 85.625756][ T10] 0 90 4294944912 [ 85.627396][ T10] 0 90 4294944912 [ 85.629043][ T10] 1 90 4294944912 [ 85.630725][ T10] 2 90 4294944912 [ 85.632383][ T10] 24 90 4294944912 [ 85.633912][ T10] 1b 90 4294944912 [ 85.635222][ T10] ff 90 4294944912 [ 85.636509][ T10] last result at 4294944913 [ 85.638202][ T10] last redo_fd_request at 4294945661 [ 85.640094][ T10] status=80 [ 85.641258][ T10] fdc_busy=1 [ 85.642508][ T10] floppy_work.func=floppy_work_workfn [ 85.644903][ T10] cont=ffffffff8ad02f20 [ 85.646797][ T10] current_req=0000000000000000 [ 85.648984][ T10] command_status=-1 [ 85.650737][ T10] [ 85.651885][ T10] floppy1: floppy timeout called [ 85.666305][ T5669] floppy1: reschedule timeout lock fdc [ 85.666363][ T4922] [ 85.668752][ T5669] floppy1: setting NEWCHANGE in poll_drive [ 85.669718][ T4922] floppy driver state [ 85.669726][ T4922] ------------------- [ 85.675986][ T4922] now=4294945672 last interrupt=4294944912 diff=760 last called handler=main_command_interrupt [ 85.680355][ T4922] timeout_message=lock fdc [ 85.681946][ T4922] last output bytes: [ 85.683326][ T4922] 0 90 4294944909 [ 85.684948][ T4922] 1 90 4294944909 [ 85.686504][ T4922] 2 90 4294944909 [ 85.688005][ T4922] 12 90 4294944909 [ 85.689507][ T4922] 1b 90 4294944909 [ 85.690836][ T4922] ff 90 4294944909 [ 85.692370][ T4922] 12 80 4294944912 [ 85.694050][ T4922] 3 90 4294944912 [ 85.695701][ T4922] 3 80 4294944912 [ 85.697262][ T4922] a1 90 4294944912 [ 85.698584][ T4922] 1e 90 4294944912 [ 85.699888][ T4922] e6 80 4294944912 [ 85.701210][ T4922] 1 90 4294944912 [ 85.702517][ T4922] 0 90 4294944912 [ 85.703899][ T4922] 0 90 4294944912 [ 85.705324][ T4922] 1 90 4294944912 [ 85.706636][ T4922] 2 90 4294944912 [ 85.707992][ T4922] 24 90 4294944912 [ 85.709636][ T4922] 1b 90 4294944912 [ 85.711007][ T4922] ff 90 4294944912 [ 85.712434][ T4922] last result at 4294944913 [ 85.714441][ T4922] last redo_fd_request at 4294945670 [ 85.717006][ T4922] status=80 [ 85.718161][ T4922] fdc_busy=1 [ 85.719299][ T4922] floppy_work.func=floppy_work_workfn [ 85.721166][ T4922] cont=ffffffff8ad02f20 [ 85.722630][ T4922] current_req=0000000000000000 [ 85.724550][ T4922] command_status=-1 [ 85.725994][ T4922] [ 85.726874][ T4922] floppy1: floppy timeout called [ 85.740907][ T5672] floppy1: reschedule timeout lock fdc [ 85.743322][ T5672] floppy1: setting NEWCHANGE in poll_drive [ 85.743525][ T4922] [ 85.746741][ T4922] floppy driver state [ 85.748352][ T4922] ------------------- [ 85.749975][ T4922] now=4294945679 last interrupt=4294944912 diff=767 last called handler=main_command_interrupt [ 85.754249][ T4922] timeout_message=lock fdc [ 85.755924][ T4922] last output bytes: [ 85.757622][ T4922] 0 90 4294944909 [ 85.759189][ T4922] 1 90 4294944909 [ 85.760538][ T4922] 2 90 4294944909 [ 85.762272][ T4922] 12 90 4294944909 [ 85.764088][ T4922] 1b 90 4294944909 [ 85.765736][ T4922] ff 90 4294944909 [ 85.767282][ T4922] 12 80 4294944912 [ 85.768643][ T4922] 3 90 4294944912 [ 85.769990][ T4922] 3 80 4294944912 [ 85.771315][ T4922] a1 90 4294944912 [ 85.772680][ T4922] 1e 90 4294944912 [ 85.774238][ T4922] e6 80 4294944912 [ 85.775774][ T4922] 1 90 4294944912 [ 85.778161][ T4922] 0 90 4294944912 [ 85.779747][ T4922] 0 90 4294944912 [ 85.781360][ T4922] 1 90 4294944912 [ 85.782923][ T4922] 2 90 4294944912 [ 85.784547][ T4922] 24 90 4294944912 [ 85.786140][ T4922] 1b 90 4294944912 [ 85.787520][ T4922] ff 90 4294944912 [ 85.789081][ T4922] last result at 4294944913 [ 85.790948][ T4922] last redo_fd_request at 4294945677 [ 85.794344][ T4922] status=80 [ 85.795720][ T4922] fdc_busy=1 [ 85.797181][ T4922] floppy_work.func=floppy_work_workfn [ 85.799298][ T4922] cont=ffffffff8ad02f20 [ 85.800898][ T4922] current_req=0000000000000000 [ 85.802783][ T4922] command_status=-1 [ 85.804303][ T4922] [ 85.805401][ T4922] floppy1: floppy timeout called [ 85.821171][ T5675] floppy1: reschedule timeout lock fdc [ 85.823513][ T4922] [ 85.823583][ T5675] floppy1: setting NEWCHANGE in poll_drive [ 85.824346][ T4922] floppy driver state [ 85.824351][ T4922] ------------------- [ 85.824354][ T4922] now=4294945687 last interrupt=4294944912 diff=775 last called handler=main_command_interrupt [ 85.835097][ T4922] timeout_message=lock fdc [ 85.837085][ T4922] last output bytes: [ 85.838784][ T4922] 0 90 4294944909 [ 85.840279][ T4922] 1 90 4294944909 [ 85.841741][ T4922] 2 90 4294944909 [ 85.843150][ T4922] 12 90 4294944909 [ 85.844635][ T4922] 1b 90 4294944909 [ 85.846128][ T4922] ff 90 4294944909 [ 85.847704][ T4922] 12 80 4294944912 [ 85.849258][ T4922] 3 90 4294944912 [ 85.850608][ T4922] 3 80 4294944912 [ 85.851917][ T4922] a1 90 4294944912 [ 85.853327][ T4922] 1e 90 4294944912 [ 85.854935][ T4922] e6 80 4294944912 [ 85.856351][ T4922] 1 90 4294944912 [ 85.857684][ T4922] 0 90 4294944912 [ 85.858958][ T4922] 0 90 4294944912 [ 85.860193][ T4922] 1 90 4294944912 [ 85.861602][ T4922] 2 90 4294944912 [ 85.862857][ T4922] 24 90 4294944912 [ 85.864201][ T4922] 1b 90 4294944912 [ 85.865572][ T4922] ff 90 4294944912 [ 85.866892][ T4922] last result at 4294944913 [ 85.868579][ T4922] last redo_fd_request at 4294945685 [ 85.870333][ T4922] status=80 [ 85.871463][ T4922] fdc_busy=1 [ 85.872755][ T4922] floppy_work.func=floppy_work_workfn [ 85.874597][ T4922] cont=ffffffff8ad02f20 [ 85.876070][ T4922] current_req=0000000000000000 [ 85.877673][ T4922] command_status=-1 [ 85.879087][ T4922] [ 85.880098][ T4922] floppy1: floppy timeout called [ 85.894539][ T5678] floppy1: reschedule timeout lock fdc [ 85.896658][ T4922] [ 85.897589][ T4922] floppy driver state [ 85.899066][ T4922] ------------------- [ 85.900528][ T4922] now=4294945694 last interrupt=4294944912 diff=782 last called handler=main_command_interrupt [ 85.901821][ T5678] floppy1: setting NEWCHANGE in poll_drive [ 85.904528][ T4922] timeout_message=lock fdc [ 85.904534][ T4922] last output bytes: [ 85.904537][ T4922] 0 90 4294944909 [ 85.904543][ T4922] 1 90 4294944909 [ 85.904547][ T4922] 2 90 4294944909 [ 85.916033][ T4922] 12 90 4294944909 [ 85.917498][ T4922] 1b 90 4294944909 [ 85.918874][ T4922] ff 90 4294944909 [ 85.920312][ T4922] 12 80 4294944912 [ 85.921980][ T4922] 3 90 4294944912 [ 85.923746][ T4922] 3 80 4294944912 [ 85.925504][ T4922] a1 90 4294944912 [ 85.926877][ T4922] 1e 90 4294944912 [ 85.928376][ T4922] e6 80 4294944912 [ 85.930030][ T4922] 1 90 4294944912 [ 85.931650][ T4922] 0 90 4294944912 [ 85.933308][ T4922] 0 90 4294944912 [ 85.935375][ T4922] 1 90 4294944912 [ 85.937037][ T4922] 2 90 4294944912 [ 85.938745][ T4922] 24 90 4294944912 [ 85.940427][ T4922] 1b 90 4294944912 [ 85.942111][ T4922] ff 90 4294944912 [ 85.943928][ T4922] last result at 4294944913 [ 85.946842][ T4922] last redo_fd_request at 4294945692 [ 85.949214][ T4922] status=80 [ 85.950638][ T4922] fdc_busy=1 [ 85.952478][ T4922] floppy_work.func=floppy_work_workfn [ 85.955190][ T4922] cont=ffffffff8ad02f20 [ 85.956659][ T4922] current_req=0000000000000000 [ 85.958445][ T4922] command_status=-1 [ 85.960098][ T4922] [ 85.961195][ T4922] floppy1: floppy timeout called [ 85.975407][ T5681] floppy1: reschedule timeout lock fdc [ 85.975440][ T10] [ 85.975448][ T10] floppy driver state [ 85.977941][ T5681] floppy1: setting NEWCHANGE in poll_drive [ 85.978755][ T10] ------------------- [ 85.978760][ T10] now=4294945702 last interrupt=4294944912 diff=790 last called handler=main_command_interrupt [ 85.988613][ T10] timeout_message=lock fdc [ 85.990392][ T10] last output bytes: [ 85.991785][ T10] 0 90 4294944909 [ 85.993242][ T10] 1 90 4294944909 [ 85.995263][ T10] 2 90 4294944909 [ 85.996926][ T10] 12 90 4294944909 [ 85.998503][ T10] 1b 90 4294944909 [ 85.999920][ T10] ff 90 4294944909 [ 86.001431][ T10] 12 80 4294944912 [ 86.002965][ T10] 3 90 4294944912 [ 86.005074][ T10] 3 80 4294944912 [ 86.006390][ T10] a1 90 4294944912 [ 86.007712][ T10] 1e 90 4294944912 [ 86.009021][ T10] e6 80 4294944912 [ 86.010329][ T10] 1 90 4294944912 [ 86.011697][ T10] 0 90 4294944912 [ 86.013064][ T10] 0 90 4294944912 [ 86.015267][ T10] 1 90 4294944912 [ 86.016980][ T10] 2 90 4294944912 [ 86.018624][ T10] 24 90 4294944912 [ 86.020270][ T10] 1b 90 4294944912 [ 86.021769][ T10] ff 90 4294944912 [ 86.023201][ T10] last result at 4294944913 [ 86.025499][ T10] last redo_fd_request at 4294945701 [ 86.027915][ T10] status=80 [ 86.029328][ T10] fdc_busy=1 [ 86.030760][ T10] floppy_work.func=floppy_work_workfn [ 86.033115][ T10] cont=ffffffff8ad02f20 [ 86.035262][ T10] current_req=0000000000000000 [ 86.037367][ T10] command_status=-1 [ 86.038882][ T10] [ 86.039754][ T10] floppy1: floppy timeout called [ 86.054858][ T5684] floppy1: reschedule timeout lock fdc [ 86.056791][ T5684] floppy1: setting NEWCHANGE in poll_drive [ 86.058830][ T10] [ 86.059650][ T10] floppy driver state [ 86.061032][ T10] ------------------- [ 86.062448][ T10] now=4294945710 last interrupt=4294944912 diff=798 last called handler=main_command_interrupt [ 86.067601][ T10] timeout_message=lock fdc [ 86.069295][ T10] last output bytes: [ 86.070656][ T10] 0 90 4294944909 [ 86.071973][ T10] 1 90 4294944909 [ 86.073563][ T10] 2 90 4294944909 [ 86.075146][ T10] 12 90 4294944909 [ 86.076445][ T10] 1b 90 4294944909 [ 86.077748][ T10] ff 90 4294944909 [ 86.079048][ T10] 12 80 4294944912 [ 86.080356][ T10] 3 90 4294944912 [ 86.081673][ T10] 3 80 4294944912 [ 86.083023][ T10] a1 90 4294944912 [ 86.084905][ T10] 1e 90 4294944912 [ 86.086585][ T10] e6 80 4294944912 [ 86.088257][ T10] 1 90 4294944912 [ 86.089935][ T10] 0 90 4294944912 [ 86.091567][ T10] 0 90 4294944912 [ 86.093204][ T10] 1 90 4294944912 [ 86.094996][ T10] 2 90 4294944912 [ 86.096605][ T10] 24 90 4294944912 [ 86.098258][ T10] 1b 90 4294944912 [ 86.099835][ T10] ff 90 4294944912 [ 86.101436][ T10] last result at 4294944913 [ 86.103073][ T10] last redo_fd_request at 4294945708 [ 86.105591][ T10] status=80 [ 86.107050][ T10] fdc_busy=1 [ 86.108429][ T10] floppy_work.func=floppy_work_workfn [ 86.110286][ T10] cont=ffffffff8ad02f20 [ 86.111720][ T10] current_req=0000000000000000 [ 86.113622][ T10] command_status=-1 [ 86.115266][ T10] [ 86.116378][ T10] floppy1: floppy timeout called [ 86.142134][ T5687] floppy1: reschedule timeout lock fdc [ 86.142322][ T4922] [ 86.144573][ T5687] floppy1: setting NEWCHANGE in poll_drive [ 86.145574][ T4922] floppy driver state [ 86.149601][ T4922] ------------------- [ 86.151309][ T4922] now=4294945719 last interrupt=4294944912 diff=807 last called handler=main_command_interrupt [ 86.155803][ T4922] timeout_message=lock fdc [ 86.157818][ T4922] last output bytes: [ 86.159492][ T4922] 0 90 4294944909 [ 86.161164][ T4922] 1 90 4294944909 [ 86.162818][ T4922] 2 90 4294944909 [ 86.164871][ T4922] 12 90 4294944909 [ 86.166495][ T4922] 1b 90 4294944909 [ 86.168169][ T4922] ff 90 4294944909 [ 86.169818][ T4922] 12 80 4294944912 [ 86.171427][ T4922] 3 90 4294944912 [ 86.173013][ T4922] 3 80 4294944912 [ 86.174750][ T4922] a1 90 4294944912 [ 86.176575][ T4922] 1e 90 4294944912 [ 86.178365][ T4922] e6 80 4294944912 [ 86.180119][ T4922] 1 90 4294944912 [ 86.181822][ T4922] 0 90 4294944912 [ 86.183746][ T4922] 0 90 4294944912 [ 86.185379][ T4922] 1 90 4294944912 [ 86.186963][ T4922] 2 90 4294944912 [ 86.188654][ T4922] 24 90 4294944912 [ 86.190311][ T4922] 1b 90 4294944912 [ 86.191974][ T4922] ff 90 4294944912 [ 86.193809][ T4922] last result at 4294944913 [ 86.195827][ T4922] last redo_fd_request at 4294945716 [ 86.198109][ T4922] status=80 [ 86.199474][ T4922] fdc_busy=1 [ 86.200888][ T4922] floppy_work.func=floppy_work_workfn [ 86.203169][ T4922] cont=ffffffff8ad02f20 [ 86.205049][ T4922] current_req=0000000000000000 [ 86.207116][ T4922] command_status=-1 [ 86.208759][ T4922] [ 86.209826][ T4922] floppy1: floppy timeout called [ 86.223938][ T5690] floppy1: reschedule timeout lock fdc [ 86.226420][ T5690] floppy1: setting NEWCHANGE in poll_drive [ 86.228479][ T4922] [ 86.229337][ T4922] floppy driver state [ 86.230781][ T4922] ------------------- [ 86.232511][ T4922] now=4294945727 last interrupt=4294944912 diff=815 last called handler=main_command_interrupt [ 86.237067][ T4922] timeout_message=lock fdc [ 86.238844][ T4922] last output bytes: [ 86.240386][ T4922] 0 90 4294944909 [ 86.241882][ T4922] 1 90 4294944909 [ 86.243248][ T4922] 2 90 4294944909 [ 86.245025][ T4922] 12 90 4294944909 [ 86.246457][ T4922] 1b 90 4294944909 [ 86.247815][ T4922] ff 90 4294944909 [ 86.249231][ T4922] 12 80 4294944912 [ 86.250634][ T4922] 3 90 4294944912 [ 86.252010][ T4922] 3 80 4294944912 [ 86.253775][ T4922] a1 90 4294944912 [ 86.255191][ T4922] 1e 90 4294944912 [ 86.256613][ T4922] e6 80 4294944912 [ 86.258273][ T4922] 1 90 4294944912 [ 86.259907][ T4922] 0 90 4294944912 [ 86.261548][ T4922] 0 90 4294944912 [ 86.263042][ T4922] 1 90 4294944912 [ 86.264861][ T4922] 2 90 4294944912 [ 86.266286][ T4922] 24 90 4294944912 [ 86.267847][ T4922] 1b 90 4294944912 [ 86.269175][ T4922] ff 90 4294944912 [ 86.270515][ T4922] last result at 4294944913 [ 86.272366][ T4922] last redo_fd_request at 4294945725 [ 86.274993][ T4922] status=80 [ 86.276181][ T4922] fdc_busy=1 [ 86.277484][ T4922] floppy_work.func=floppy_work_workfn [ 86.279420][ T4922] cont=ffffffff8ad02f20 [ 86.281109][ T4922] current_req=0000000000000000 [ 86.283118][ T4922] command_status=-1 [ 86.284578][ T4922] [ 86.285529][ T4922] floppy1: floppy timeout called [ 86.297775][ T5693] floppy1: reschedule timeout lock fdc [ 86.297858][ T5219] [ 86.299898][ T5693] floppy1: setting NEWCHANGE in poll_drive [ 86.300925][ T5219] floppy driver state [ 86.300932][ T5219] ------------------- [ 86.300937][ T5219] now=4294945734 last interrupt=4294944912 diff=822 last called handler=main_command_interrupt [ 86.300963][ T5219] timeout_message=lock fdc [ 86.300969][ T5219] last output bytes: [ 86.300974][ T5219] 0 90 4294944909 [ 86.315844][ T5219] 1 90 4294944909 [ 86.317218][ T5219] 2 90 4294944909 [ 86.318544][ T5219] 12 90 4294944909 [ 86.319847][ T5219] 1b 90 4294944909 [ 86.321254][ T5219] ff 90 4294944909 [ 86.322706][ T5219] 12 80 4294944912 [ 86.324434][ T5219] 3 90 4294944912 [ 86.326030][ T5219] 3 80 4294944912 [ 86.327585][ T5219] a1 90 4294944912 [ 86.329192][ T5219] 1e 90 4294944912 [ 86.330803][ T5219] e6 80 4294944912 [ 86.332378][ T5219] 1 90 4294944912 [ 86.334222][ T5219] 0 90 4294944912 [ 86.335562][ T5219] 0 90 4294944912 [ 86.337037][ T5219] 1 90 4294944912 [ 86.338533][ T5219] 2 90 4294944912 [ 86.340231][ T5219] 24 90 4294944912 [ 86.341965][ T5219] 1b 90 4294944912 [ 86.343870][ T5219] ff 90 4294944912 [ 86.345636][ T5219] last result at 4294944913 [ 86.347675][ T5219] last redo_fd_request at 4294945733 [ 86.350041][ T5219] status=80 [ 86.351270][ T5219] fdc_busy=1 [ 86.352474][ T5219] floppy_work.func=floppy_work_workfn [ 86.354574][ T5219] cont=ffffffff8ad02f20 [ 86.356087][ T5219] current_req=0000000000000000 [ 86.358047][ T5219] command_status=-1 [ 86.359726][ T5219] [ 86.360817][ T5219] floppy1: floppy timeout called [ 86.381664][ T5696] floppy1: reschedule timeout lock fdc [ 86.381702][ T5219] [ 86.384281][ T5696] floppy1: setting NEWCHANGE in poll_drive [ 86.385143][ T5219] floppy driver state [ 86.385149][ T5219] ------------------- [ 86.391052][ T5219] now=4294945743 last interrupt=4294944912 diff=831 last called handler=main_command_interrupt [ 86.395561][ T5219] timeout_message=lock fdc [ 86.397511][ T5219] last output bytes: [ 86.399242][ T5219] 0 90 4294944909 [ 86.400881][ T5219] 1 90 4294944909 [ 86.402508][ T5219] 2 90 4294944909 [ 86.404212][ T5219] 12 90 4294944909 [ 86.405861][ T5219] 1b 90 4294944909 [ 86.407490][ T5219] ff 90 4294944909 [ 86.409052][ T5219] 12 80 4294944912 [ 86.410624][ T5219] 3 90 4294944912 [ 86.412220][ T5219] 3 80 4294944912 [ 86.414381][ T5219] a1 90 4294944912 [ 86.416024][ T5219] 1e 90 4294944912 [ 86.417688][ T5219] e6 80 4294944912 [ 86.419048][ T5219] 1 90 4294944912 [ 86.420419][ T5219] 0 90 4294944912 [ 86.421829][ T5219] 0 90 4294944912 [ 86.423077][ T5219] 1 90 4294944912 [ 86.424504][ T5219] 2 90 4294944912 [ 86.425904][ T5219] 24 90 4294944912 [ 86.427315][ T5219] 1b 90 4294944912 [ 86.428669][ T5219] ff 90 4294944912 [ 86.430000][ T5219] last result at 4294944913 [ 86.431760][ T5219] last redo_fd_request at 4294945740 [ 86.434064][ T5219] status=80 [ 86.435472][ T5219] fdc_busy=1 [ 86.436841][ T5219] floppy_work.func=floppy_work_workfn [ 86.438929][ T5219] cont=ffffffff8ad02f20 [ 86.440549][ T5219] current_req=0000000000000000 [ 86.442315][ T5219] command_status=-1 [ 86.444130][ T5219] [ 86.445179][ T5219] floppy1: floppy timeout called [ 86.460516][ T5699] floppy1: reschedule timeout lock fdc [ 86.460547][ T5219] [ 86.462930][ T5699] floppy1: setting NEWCHANGE in poll_drive [ 86.463801][ T5219] floppy driver state [ 86.463806][ T5219] ------------------- [ 86.463809][ T5219] now=4294945751 last interrupt=4294944912 diff=839 last called handler=main_command_interrupt [ 86.463825][ T5219] timeout_message=lock fdc [ 86.463828][ T5219] last output bytes: [ 86.476970][ T5219] 0 90 4294944909 [ 86.478399][ T5219] 1 90 4294944909 [ 86.479955][ T5219] 2 90 4294944909 [ 86.481471][ T5219] 12 90 4294944909 [ 86.482900][ T5219] 1b 90 4294944909 [ 86.484543][ T5219] ff 90 4294944909 [ 86.486011][ T5219] 12 80 4294944912 [ 86.487565][ T5219] 3 90 4294944912 [ 86.489013][ T5219] 3 80 4294944912 [ 86.490551][ T5219] a1 90 4294944912 [ 86.492149][ T5219] 1e 90 4294944912 [ 86.493624][ T5219] e6 80 4294944912 [ 86.495074][ T5219] 1 90 4294944912 [ 86.496798][ T5219] 0 90 4294944912 [ 86.498542][ T5219] 0 90 4294944912 [ 86.500027][ T5219] 1 90 4294944912 [ 86.501397][ T5219] 2 90 4294944912 [ 86.502934][ T5219] 24 90 4294944912 [ 86.504414][ T5219] 1b 90 4294944912 [ 86.505858][ T5219] ff 90 4294944912 [ 86.507388][ T5219] last result at 4294944913 [ 86.509071][ T5219] last redo_fd_request at 4294945749 [ 86.510945][ T5219] status=80 [ 86.512140][ T5219] fdc_busy=1 [ 86.513635][ T5219] floppy_work.func=floppy_work_workfn [ 86.515973][ T5219] cont=ffffffff8ad02f20 [ 86.517790][ T5219] current_req=0000000000000000 [ 86.519573][ T5219] command_status=-1 [ 86.520927][ T5219] [ 86.521801][ T5219] floppy1: floppy timeout called [ 86.540451][ T5702] floppy1: reschedule timeout lock fdc [ 86.540501][ T5219] [ 86.542871][ T5702] floppy1: setting NEWCHANGE in poll_drive [ 86.543852][ T5219] floppy driver state [ 86.543858][ T5219] ------------------- [ 86.543863][ T5219] now=4294945759 last interrupt=4294944912 diff=847 last called handler=main_command_interrupt [ 86.543886][ T5219] timeout_message=lock fdc [ 86.543892][ T5219] last output bytes: [ 86.543896][ T5219] 0 90 4294944909 [ 86.543905][ T5219] 1 90 4294944909 [ 86.543913][ T5219] 2 90 4294944909 [ 86.543921][ T5219] 12 90 4294944909 [ 86.543929][ T5219] 1b 90 4294944909 [ 86.543937][ T5219] ff 90 4294944909 [ 86.543944][ T5219] 12 80 4294944912 [ 86.543952][ T5219] 3 90 4294944912 [ 86.543960][ T5219] 3 80 4294944912 [ 86.543968][ T5219] a1 90 4294944912 [ 86.543976][ T5219] 1e 90 4294944912 [ 86.543984][ T5219] e6 80 4294944912 [ 86.543992][ T5219] 1 90 4294944912 [ 86.579246][ T5219] 0 90 4294944912 [ 86.580973][ T5219] 0 90 4294944912 [ 86.582673][ T5219] 1 90 4294944912 [ 86.584557][ T5219] 2 90 4294944912 [ 86.586358][ T5219] 24 90 4294944912 [ 86.588103][ T5219] 1b 90 4294944912 [ 86.589798][ T5219] ff 90 4294944912 [ 86.592482][ T5219] last result at 4294944913 [ 86.595204][ T5219] last redo_fd_request at 4294945757 [ 86.597655][ T5219] status=80 [ 86.599104][ T5219] fdc_busy=1 [ 86.600620][ T5219] floppy_work.func=floppy_work_workfn [ 86.603056][ T5219] cont=ffffffff8ad02f20 [ 86.605017][ T5219] current_req=0000000000000000 [ 86.607122][ T5219] command_status=-1 [ 86.608812][ T5219] [ 86.609946][ T5219] floppy1: floppy timeout called [ 86.625642][ T5705] floppy1: reschedule timeout lock fdc [ 86.625702][ T5219] [ 86.628120][ T5705] floppy1: setting NEWCHANGE in poll_drive [ 86.628922][ T5219] floppy driver state [ 86.628929][ T5219] ------------------- [ 86.635112][ T5219] now=4294945768 last interrupt=4294944912 diff=856 last called handler=main_command_interrupt [ 86.639368][ T5219] timeout_message=lock fdc [ 86.641088][ T5219] last output bytes: [ 86.642781][ T5219] 0 90 4294944909 [ 86.644643][ T5219] 1 90 4294944909 [ 86.646269][ T5219] 2 90 4294944909 [ 86.647832][ T5219] 12 90 4294944909 [ 86.649517][ T5219] 1b 90 4294944909 [ 86.651054][ T5219] ff 90 4294944909 [ 86.652430][ T5219] 12 80 4294944912 [ 86.653896][ T5219] 3 90 4294944912 [ 86.655249][ T5219] 3 80 4294944912 [ 86.656564][ T5219] a1 90 4294944912 [ 86.657932][ T5219] 1e 90 4294944912 [ 86.659279][ T5219] e6 80 4294944912 [ 86.660638][ T5219] 1 90 4294944912 [ 86.661986][ T5219] 0 90 4294944912 [ 86.663582][ T5219] 0 90 4294944912 [ 86.665099][ T5219] 1 90 4294944912 [ 86.666925][ T5219] 2 90 4294944912 [ 86.668305][ T5219] 24 90 4294944912 [ 86.669686][ T5219] 1b 90 4294944912 [ 86.671020][ T5219] ff 90 4294944912 [ 86.672357][ T5219] last result at 4294944913 [ 86.674505][ T5219] last redo_fd_request at 4294945765 [ 86.676672][ T5219] status=80 [ 86.678070][ T5219] fdc_busy=1 [ 86.679424][ T5219] floppy_work.func=floppy_work_workfn [ 86.681679][ T5219] cont=ffffffff8ad02f20 [ 86.683353][ T5219] current_req=0000000000000000 [ 86.685549][ T5219] command_status=-1 [ 86.687140][ T5219] [ 86.688105][ T5219] floppy1: floppy timeout called [ 86.710367][ T5708] floppy1: reschedule timeout lock fdc [ 86.710414][ T5219] [ 86.712655][ T5708] floppy1: setting NEWCHANGE in poll_drive [ 86.713902][ T5219] floppy driver state [ 86.717888][ T5219] ------------------- [ 86.719650][ T5219] now=4294945776 last interrupt=4294944912 diff=864 last called handler=main_command_interrupt [ 86.724357][ T5219] timeout_message=lock fdc [ 86.726320][ T5219] last output bytes: [ 86.728075][ T5219] 0 90 4294944909 [ 86.729775][ T5219] 1 90 4294944909 [ 86.731464][ T5219] 2 90 4294944909 [ 86.733183][ T5219] 12 90 4294944909 [ 86.735376][ T5219] 1b 90 4294944909 [ 86.737156][ T5219] ff 90 4294944909 [ 86.738872][ T5219] 12 80 4294944912 [ 86.740546][ T5219] 3 90 4294944912 [ 86.742308][ T5219] 3 80 4294944912 [ 86.744192][ T5219] a1 90 4294944912 [ 86.745909][ T5219] 1e 90 4294944912 [ 86.747621][ T5219] e6 80 4294944912 [ 86.749360][ T5219] 1 90 4294944912 [ 86.751071][ T5219] 0 90 4294944912 [ 86.752791][ T5219] 0 90 4294944912 [ 86.754625][ T5219] 1 90 4294944912 [ 86.756275][ T5219] 2 90 4294944912 [ 86.757986][ T5219] 24 90 4294944912 [ 86.759607][ T5219] 1b 90 4294944912 [ 86.761278][ T5219] ff 90 4294944912 [ 86.762942][ T5219] last result at 4294944913 [ 86.766035][ T5219] last redo_fd_request at 4294945773 [ 86.768417][ T5219] status=80 [ 86.769829][ T5219] fdc_busy=1 [ 86.771264][ T5219] floppy_work.func=floppy_work_workfn [ 86.773754][ T5219] cont=ffffffff8ad02f20 [ 86.775580][ T5219] current_req=0000000000000000 [ 86.777747][ T5219] command_status=-1 [ 86.779440][ T5219] [ 86.780551][ T5219] floppy1: floppy timeout called [ 86.794655][ T5711] floppy1: reschedule timeout lock fdc [ 86.794691][ T4922] [ 86.797115][ T5711] floppy1: setting NEWCHANGE in poll_drive [ 86.797896][ T4922] floppy driver state [ 86.797901][ T4922] ------------------- [ 86.803160][ T4922] now=4294945784 last interrupt=4294944912 diff=872 last called handler=main_command_interrupt [ 86.806903][ T4922] timeout_message=lock fdc [ 86.808553][ T4922] last output bytes: [ 86.809939][ T4922] 0 90 4294944909 [ 86.811250][ T4922] 1 90 4294944909 [ 86.812716][ T4922] 2 90 4294944909 [ 86.814390][ T4922] 12 90 4294944909 [ 86.815734][ T4922] 1b 90 4294944909 [ 86.817086][ T4922] ff 90 4294944909 [ 86.818430][ T4922] 12 80 4294944912 [ 86.819760][ T4922] 3 90 4294944912 [ 86.821411][ T4922] 3 80 4294944912 [ 86.823118][ T4922] a1 90 4294944912 [ 86.825366][ T4922] 1e 90 4294944912 [ 86.827153][ T4922] e6 80 4294944912 [ 86.828858][ T4922] 1 90 4294944912 [ 86.830543][ T4922] 0 90 4294944912 [ 86.832219][ T4922] 0 90 4294944912 [ 86.834023][ T4922] 1 90 4294944912 [ 86.835656][ T4922] 2 90 4294944912 [ 86.837293][ T4922] 24 90 4294944912 [ 86.838914][ T4922] 1b 90 4294944912 [ 86.840511][ T4922] ff 90 4294944912 [ 86.842054][ T4922] last result at 4294944913 [ 86.845063][ T4922] last redo_fd_request at 4294945782 [ 86.847262][ T4922] status=80 [ 86.848628][ T4922] fdc_busy=1 [ 86.850050][ T4922] floppy_work.func=floppy_work_workfn [ 86.852287][ T4922] cont=ffffffff8ad02f20 [ 86.854169][ T4922] current_req=0000000000000000 [ 86.856221][ T4922] command_status=-1 [ 86.857853][ T4922] [ 86.858901][ T4922] floppy1: floppy timeout called [ 86.874974][ T5714] floppy1: reschedule timeout lock fdc [ 86.875244][ T963] cfg80211: failed to load regulatory.db [ 86.877314][ T5714] floppy1: setting NEWCHANGE in poll_drive [ 86.877968][ T4922] [ 86.877974][ T4922] floppy driver state [ 86.877978][ T4922] ------------------- [ 86.877983][ T4922] now=4294945792 last interrupt=4294944912 diff=880 last called handler=main_command_interrupt [ 86.878005][ T4922] timeout_message=lock fdc [ 86.878011][ T4922] last output bytes: [ 86.878015][ T4922] 0 90 4294944909 [ 86.878023][ T4922] 1 90 4294944909 [ 86.878030][ T4922] 2 90 4294944909 [ 86.878037][ T4922] 12 90 4294944909 [ 86.878045][ T4922] 1b 90 4294944909 [ 86.878053][ T4922] ff 90 4294944909 [ 86.878060][ T4922] 12 80 4294944912 [ 86.878068][ T4922] 3 90 4294944912 [ 86.878132][ T4922] 3 80 4294944912 [ 86.878141][ T4922] a1 90 4294944912 [ 86.878150][ T4922] 1e 90 4294944912 [ 86.878157][ T4922] e6 80 4294944912 [ 86.878164][ T4922] 1 90 4294944912 [ 86.878172][ T4922] 0 90 4294944912 [ 86.878179][ T4922] 0 90 4294944912 [ 86.878187][ T4922] 1 90 4294944912 [ 86.878194][ T4922] 2 90 4294944912 [ 86.878201][ T4922] 24 90 4294944912 [ 86.878210][ T4922] 1b 90 4294944912 [ 86.878217][ T4922] ff 90 4294944912 [ 86.878225][ T4922] last result at 4294944913 [ 86.878230][ T4922] last redo_fd_request at 4294945790 [ 86.878269][ T4922] status=80 [ 86.878276][ T4922] fdc_busy=1 [ 86.878281][ T4922] cont=ffffffff8ad02f20 [ 86.878287][ T4922] current_req=0000000000000000 [ 86.878293][ T4922] command_status=-1 [ 86.878298][ T4922] [ 86.878350][ T4922] floppy1: floppy timeout called [ 86.942232][ T5714] ------------[ cut here ]------------ [ 86.943090][ T4922] reset_interrupt: dtime=930 [ 86.944641][ T5714] WARNING: CPU: 2 PID: 5714 at drivers/block/floppy.c:999 floppy_revalidate.isra.0+0x878/0xbf0 [ 86.950647][ T5714] Modules linked in: [ 86.952483][ T5714] CPU: 2 PID: 5714 Comm: syz-executor.0 Not tainted 6.4.0-rc4-syzkaller-g7877cb91f108 #0 [ 86.958075][ T5714] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.14.0-2 04/01/2014 [ 86.961918][ T5714] RIP: 0010:floppy_revalidate.isra.0+0x878/0xbf0 [ 86.964800][ T5714] Code: 45 8d e8 fb 0b 1e ff 48 83 fb 07 0f 86 5d f8 ff ff 48 89 de 48 c7 c7 60 b9 45 8d e8 e2 0b 1e ff e9 49 f8 ff ff e8 e8 22 60 fc <0f> 0b e9 07 fd ff ff e8 dc 22 60 fc 48 83 fb 07 0f 87 26 03 00 00 [ 86.973073][ T5714] RSP: 0018:ffffc90004a2f8d8 EFLAGS: 00010293 [ 86.975830][ T5714] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000 [ 86.979395][ T5714] RDX: ffff88802a6fcbc0 RSI: ffffffff85234658 RDI: 0000000000000001 [ 86.982924][ T5714] RBP: 0000000000000001 R08: 0000000000000001 R09: 0000000000000000 [ 86.986652][ T5714] R10: 0000000000000001 R11: 0000000000000001 R12: ffff88801f75d000 [ 86.990203][ T5714] R13: ffffffff920decf0 R14: 0000000000000050 R15: 0000000000000001 [ 86.993800][ T5714] FS: 00007f9d9b004700(0000) GS:ffff88806b800000(0000) knlGS:0000000000000000 [ 86.997762][ T5714] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 87.000597][ T5714] CR2: 00007f9d9b003d38 CR3: 0000000022952000 CR4: 0000000000350ee0 [ 87.004107][ T5714] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 87.007563][ T5714] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 [ 87.010975][ T5714] Call Trace: [ 87.012664][ T5714] [ 87.014182][ T5714] ? __warn+0xe6/0x390 [ 87.016001][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.018596][ T5714] ? report_bug+0x2da/0x500 [ 87.020637][ T5714] ? handle_bug+0x3c/0x70 [ 87.022597][ T5714] ? exc_invalid_op+0x18/0x50 [ 87.024782][ T5714] ? asm_exc_invalid_op+0x1a/0x20 [ 87.027008][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.029540][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.032038][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.034745][ T5714] floppy_open+0xac3/0xe90 [ 87.036757][ T5714] ? floppy_revalidate.isra.0+0xbf0/0xbf0 [ 87.039313][ T5714] blkdev_get_whole+0x93/0x2c0 [ 87.041422][ T5714] blkdev_get_by_dev.part.0+0x5e2/0xb80 [ 87.043946][ T5714] ? devcgroup_check_permission+0x1aa/0x4a0 [ 87.046575][ T5714] blkdev_get_by_dev+0x6f/0x90 [ 87.048708][ T5714] blkdev_open+0x140/0x2d0 [ 87.050682][ T5714] do_dentry_open+0x6cc/0x13f0 [ 87.052697][ T5714] ? blkdev_close+0x80/0x80 [ 87.054910][ T5714] path_openat+0x1baa/0x2750 [ 87.056970][ T5714] ? path_lookupat+0x840/0x840 [ 87.059150][ T5714] do_filp_open+0x1ba/0x410 [ 87.061211][ T5714] ? may_open_dev+0xf0/0xf0 [ 87.063253][ T5714] ? find_held_lock+0x2d/0x110 [ 87.065456][ T5714] ? do_raw_spin_lock+0x124/0x2b0 [ 87.067716][ T5714] ? spin_bug+0x1c0/0x1c0 [ 87.069644][ T5714] ? _raw_spin_unlock+0x28/0x40 [ 87.071780][ T5714] ? alloc_fd+0x2e4/0x750 [ 87.073807][ T5714] do_sys_openat2+0x16d/0x4c0 [ 87.075728][ T5714] ? build_open_flags+0x720/0x720 [ 87.077945][ T5714] ? do_user_addr_fault+0x367/0x1210 [ 87.080363][ T5714] ? lock_downgrade+0x690/0x690 [ 87.082537][ T5714] __x64_sys_openat+0x143/0x1f0 [ 87.084828][ T5714] ? __ia32_sys_open+0x1c0/0x1c0 [ 87.087008][ T5714] ? syscall_enter_from_user_mode+0x26/0x80 [ 87.089648][ T5714] do_syscall_64+0x39/0xb0 [ 87.091639][ T5714] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 87.094311][ T5714] RIP: 0033:0x7f9d99e3bf64 [ 87.096311][ T5714] Code: 84 00 00 00 00 00 44 89 54 24 0c e8 96 f9 ff ff 44 8b 54 24 0c 44 89 e2 48 89 ee 41 89 c0 bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 34 44 89 c7 89 44 24 0c e8 c8 f9 ff ff 8b 44 [ 87.105046][ T5714] RSP: 002b:00007f9d9b003ca0 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 [ 87.108787][ T5714] RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 00007f9d99e3bf64 [ 87.112026][ T5714] RDX: 0000000000000000 RSI: 00007f9d9b003d40 RDI: 00000000ffffff9c [ 87.115337][ T5714] RBP: 00007f9d9b003d40 R08: 0000000000000000 R09: 0000000000000000 [ 87.118146][ T5714] R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 [ 87.121159][ T5714] R13: 00007fff5a7f6b6f R14: 00007f9d9b004300 R15: 0000000000022000 [ 87.124273][ T5714] [ 87.125437][ T5714] Kernel panic - not syncing: kernel: panic_on_warn set ... [ 87.128043][ T5714] CPU: 2 PID: 5714 Comm: syz-executor.0 Not tainted 6.4.0-rc4-syzkaller-g7877cb91f108 #0 [ 87.131488][ T5714] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 1.14.0-2 04/01/2014 [ 87.134712][ T5714] Call Trace: [ 87.135960][ T5714] [ 87.137091][ T5714] dump_stack_lvl+0xd9/0x150 [ 87.138857][ T5714] panic+0x686/0x730 [ 87.140300][ T5714] ? panic_smp_self_stop+0xa0/0xa0 [ 87.142174][ T5714] ? show_trace_log_lvl+0x284/0x390 [ 87.144074][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.146143][ T5714] check_panic_on_warn+0xb1/0xc0 [ 87.147964][ T5714] __warn+0xf2/0x390 [ 87.149385][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.151479][ T5714] report_bug+0x2da/0x500 [ 87.153200][ T5714] handle_bug+0x3c/0x70 [ 87.154746][ T5714] exc_invalid_op+0x18/0x50 [ 87.156435][ T5714] asm_exc_invalid_op+0x1a/0x20 [ 87.158278][ T5714] RIP: 0010:floppy_revalidate.isra.0+0x878/0xbf0 [ 87.160636][ T5714] Code: 45 8d e8 fb 0b 1e ff 48 83 fb 07 0f 86 5d f8 ff ff 48 89 de 48 c7 c7 60 b9 45 8d e8 e2 0b 1e ff e9 49 f8 ff ff e8 e8 22 60 fc <0f> 0b e9 07 fd ff ff e8 dc 22 60 fc 48 83 fb 07 0f 87 26 03 00 00 [ 87.167714][ T5714] RSP: 0018:ffffc90004a2f8d8 EFLAGS: 00010293 [ 87.170051][ T5714] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 0000000000000000 [ 87.173286][ T5714] RDX: ffff88802a6fcbc0 RSI: ffffffff85234658 RDI: 0000000000000001 [ 87.176328][ T5714] RBP: 0000000000000001 R08: 0000000000000001 R09: 0000000000000000 [ 87.179459][ T5714] R10: 0000000000000001 R11: 0000000000000001 R12: ffff88801f75d000 [ 87.182427][ T5714] R13: ffffffff920decf0 R14: 0000000000000050 R15: 0000000000000001 [ 87.185247][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.187279][ T5714] ? floppy_revalidate.isra.0+0x878/0xbf0 [ 87.189343][ T5714] floppy_open+0xac3/0xe90 [ 87.190947][ T5714] ? floppy_revalidate.isra.0+0xbf0/0xbf0 [ 87.192987][ T5714] blkdev_get_whole+0x93/0x2c0 [ 87.194970][ T5714] blkdev_get_by_dev.part.0+0x5e2/0xb80 [ 87.197340][ T5714] ? devcgroup_check_permission+0x1aa/0x4a0 [ 87.199411][ T5714] blkdev_get_by_dev+0x6f/0x90 [ 87.201106][ T5714] blkdev_open+0x140/0x2d0 [ 87.202692][ T5714] do_dentry_open+0x6cc/0x13f0 [ 87.204427][ T5714] ? blkdev_close+0x80/0x80 [ 87.206048][ T5714] path_openat+0x1baa/0x2750 [ 87.207667][ T5714] ? path_lookupat+0x840/0x840 [ 87.209372][ T5714] do_filp_open+0x1ba/0x410 [ 87.210991][ T5714] ? may_open_dev+0xf0/0xf0 [ 87.212713][ T5714] ? find_held_lock+0x2d/0x110 [ 87.214855][ T5714] ? do_raw_spin_lock+0x124/0x2b0 [ 87.217109][ T5714] ? spin_bug+0x1c0/0x1c0 [ 87.218866][ T5714] ? _raw_spin_unlock+0x28/0x40 [ 87.220991][ T5714] ? alloc_fd+0x2e4/0x750 [ 87.222638][ T5714] do_sys_openat2+0x16d/0x4c0 [ 87.224582][ T5714] ? build_open_flags+0x720/0x720 [ 87.226496][ T5714] ? do_user_addr_fault+0x367/0x1210 [ 87.228531][ T5714] ? lock_downgrade+0x690/0x690 [ 87.230505][ T5714] __x64_sys_openat+0x143/0x1f0 [ 87.232269][ T5714] ? __ia32_sys_open+0x1c0/0x1c0 [ 87.234282][ T5714] ? syscall_enter_from_user_mode+0x26/0x80 [ 87.236821][ T5714] do_syscall_64+0x39/0xb0 [ 87.238746][ T5714] entry_SYSCALL_64_after_hwframe+0x63/0xcd [ 87.241298][ T5714] RIP: 0033:0x7f9d99e3bf64 [ 87.243195][ T5714] Code: 84 00 00 00 00 00 44 89 54 24 0c e8 96 f9 ff ff 44 8b 54 24 0c 44 89 e2 48 89 ee 41 89 c0 bf 9c ff ff ff b8 01 01 00 00 0f 05 <48> 3d 00 f0 ff ff 77 34 44 89 c7 89 44 24 0c e8 c8 f9 ff ff 8b 44 [ 87.250825][ T5714] RSP: 002b:00007f9d9b003ca0 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 [ 87.254296][ T5714] RAX: ffffffffffffffda RBX: 6666666666666667 RCX: 00007f9d99e3bf64 [ 87.257460][ T5714] RDX: 0000000000000000 RSI: 00007f9d9b003d40 RDI: 00000000ffffff9c [ 87.260403][ T5714] RBP: 00007f9d9b003d40 R08: 0000000000000000 R09: 0000000000000000 [ 87.263648][ T5714] R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000 [ 87.266989][ T5714] R13: 00007fff5a7f6b6f R14: 00007f9d9b004300 R15: 0000000000022000 [ 87.270206][ T5714] [ 87.272013][ T5714] Kernel Offset: disabled [ 87.274047][ T5714] Rebooting in 86400 seconds..