22:50:09.635 (ports 5): Begin, version=1.44-beta1, high-res time 22:50:09.635 (ports 5): LoadLibrary cfgmgr32 ok 22:50:09.635 (ports 5): LoadLibrary ntdll ok 22:50:09.640 (ports 5): callback 0024 22:50:09.641 (ports 5): callback 0081 22:50:09.645 (ports 5): callback 0083 22:50:09.647 (ports 5): hWnd = 7276424 22:50:09.650 (ports 5): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#2056390#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:50:09.650 (ports 5): found_usb_device, loc=usb:0/140000/0/6/1 Port_#0001.Hub_#0005 22:50:09.650 (ports 5): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0277 22:50:09.650 (ports 5): found_usb_device, devinst=00000002 22:50:09.650 (ports 5): add: loc=usb:0/140000/0/6/1, class=Ports, vid=16C0, pid=0483, ver=0277, serial=2056390, dev=\\?\usb#vid_16c0&pid_0483#2056390#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:50:09.650 (ports 5): comport_from_devinst_list attempt 22:50:09.650 (ports 5): found Ports in classguid_list at index=0 22:50:09.650 (ports 5): port COM8 found from devnode 22:50:09.650 (ports 5): found_usb_device complete 22:50:09.651 (ports 5): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:50:09.651 (ports 5): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 22:50:09.651 (ports 5): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 22:50:09.651 (ports 5): found_usb_device, devinst=00000005 22:50:09.651 (ports 5): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:50:09.651 (ports 5): comport_from_devinst_list attempt 22:50:09.651 (ports 5): found Ports in classguid_list at index=0 22:50:09.651 (ports 5): port COM11 found from devnode 22:50:09.651 (ports 5): found_usb_device complete 22:50:09.653 (ports 5): usb_add: usb:0/140000/0/2 COM11 (Teensy LC) Serial 22:50:09.653 (ports 5): usb_add: usb:0/140000/0/6/1 COM8 (Teensy 3.6) Serial 22:50:55.428 (ports 5): callback 001A 22:54:06.838 (ports 6): Begin, version=1.44-beta1, high-res time 22:54:06.838 (ports 6): LoadLibrary cfgmgr32 ok 22:54:06.838 (ports 6): LoadLibrary ntdll ok 22:54:06.843 (ports 6): callback 0024 22:54:06.843 (ports 6): callback 0081 22:54:06.846 (ports 6): callback 0083 22:54:06.849 (ports 6): hWnd = 30482582 22:54:06.852 (ports 6): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#2056390#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:54:06.852 (ports 6): found_usb_device, loc=usb:0/140000/0/6/1 Port_#0001.Hub_#0005 22:54:06.852 (ports 6): found_usb_device, devinst=00000002 22:54:06.852 (ports 6): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0277 22:54:06.852 (ports 6): add: loc=usb:0/140000/0/6/1, class=Ports, vid=16C0, pid=0483, ver=0277, serial=2056390, dev=\\?\usb#vid_16c0&pid_0483#2056390#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:54:06.852 (ports 6): comport_from_devinst_list attempt 22:54:06.852 (ports 6): found Ports in classguid_list at index=0 22:54:06.852 (ports 6): port COM8 found from devnode 22:54:06.852 (ports 6): found_usb_device complete 22:54:06.852 (ports 6): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:54:06.852 (ports 6): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 22:54:06.852 (ports 6): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 22:54:06.852 (ports 6): found_usb_device, devinst=00000005 22:54:06.852 (ports 6): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:54:06.852 (ports 6): comport_from_devinst_list attempt 22:54:06.852 (ports 6): found Ports in classguid_list at index=0 22:54:06.852 (ports 6): port COM11 found from devnode 22:54:06.852 (ports 6): found_usb_device complete 22:54:06.854 (ports 6): usb_add: usb:0/140000/0/2 COM11 (Teensy LC) Serial 22:54:06.854 (ports 6): usb_add: usb:0/140000/0/6/1 COM8 (Teensy 3.6) Serial 22:55:01.253 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:55:01.254 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:55:01.254 (ports 5): remove: loc=usb:0/140000/0/6/1 22:55:01.254 (ports 5): usb_remove: usb:0/140000/0/6/1 22:55:01.254 (ports 5): nothing new, skipping HID & Ports enum 22:55:01.255 (ports 6): remove: loc=usb:0/140000/0/6/1 22:55:01.255 (ports 6): usb_remove: usb:0/140000/0/6/1 22:55:01.255 (ports 6): nothing new, skipping HID & Ports enum 22:55:01.264 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:55:01.265 (ports 6): nothing new, skipping HID & Ports enum 22:55:01.265 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:55:01.266 (ports 6): nothing new, skipping HID & Ports enum 22:55:01.267 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:55:01.268 (ports 5): nothing new, skipping HID & Ports enum 22:55:01.268 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:55:01.269 (ports 5): nothing new, skipping HID & Ports enum 22:55:01.719 (ports 5): WM_DEVICECHANGE DBT_DEVICEARRIVAL 22:55:01.719 (ports 6): WM_DEVICECHANGE DBT_DEVICEARRIVAL 22:55:01.721 (ports 5): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:55:01.721 (ports 5): found_usb_device, loc=usb:0/140000/0/6/1 Port_#0001.Hub_#0005 22:55:01.721 (ports 5): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0103 22:55:01.721 (ports 5): found_usb_device, devinst=0000001c 22:55:01.721 (ports 5): add: loc=usb:0/140000/0/6/1, class=HID, vid=16C0, pid=0478, ver=0103, serial=00032347, dev=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:55:01.721 (ports 5): hiddev_from_devinst_list: iface=0 22:55:01.721 (ports 6): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:55:01.721 (ports 6): found_usb_device, loc=usb:0/140000/0/6/1 Port_#0001.Hub_#0005 22:55:01.721 (ports 6): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0103 22:55:01.721 (ports 6): found_usb_device, devinst=0000001c 22:55:01.721 (ports 6): add: loc=usb:0/140000/0/6/1, class=HID, vid=16C0, pid=0478, ver=0103, serial=00032347, dev=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:55:01.721 (ports 6): hiddev_from_devinst_list: iface=0 22:55:01.722 (ports 5): found_usb_device complete 22:55:01.722 (ports 6): found_usb_device complete 22:55:01.723 (ports 5): usb_add: usb:0/140000/0/6/1 [no_device] (Teensy 3.6) Bootloader 22:55:01.723 (ports 6): usb_add: usb:0/140000/0/6/1 [no_device] (Teensy 3.6) Bootloader 22:55:01.723 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:55:01.725 (ports 6): hid, found devinst=0000001D 22:55:01.725 (ports 6): hid, path=\\?\hid#vid_16c0&pid_0478#8&1e4f6cc3&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:55:01.725 (ports 6): hid, opened handle 22:55:01.725 (ports 6): devinst=0000001D, location=usb:0/140000/0/6/1 22:55:01.725 (ports 6): vid=16C0, pid=0478, ver=0103, usepage=FF9C, use=0022 22:55:01.725 (ports 6): devpath=\\?\hid#vid_16c0&pid_0478#8&1e4f6cc3&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:55:01.726 (ports 6): usb_add: usb:0/140000/0/6/1 hid#vid_16c0&pid_0478 (Teensy 3.6) Bootloader 22:55:01.731 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:55:01.733 (ports 5): hid, found devinst=0000001D 22:55:01.733 (ports 5): hid, path=\\?\hid#vid_16c0&pid_0478#8&1e4f6cc3&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:55:01.733 (ports 5): hid, opened handle 22:55:01.733 (ports 5): devinst=0000001D, location=usb:0/140000/0/6/1 22:55:01.733 (ports 5): vid=16C0, pid=0478, ver=0103, usepage=FF9C, use=0022 22:55:01.733 (ports 5): devpath=\\?\hid#vid_16c0&pid_0478#8&1e4f6cc3&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:55:01.733 (ports 5): usb_add: usb:0/140000/0/6/1 hid#vid_16c0&pid_0478 (Teensy 3.6) Bootloader 22:55:01.754 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:55:01.755 (ports 6): nothing new, skipping HID & Ports enum 22:55:01.758 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:55:01.759 (ports 5): nothing new, skipping HID & Ports enum 22:56:10.156 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:56:10.156 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:56:10.157 (ports 5): remove: loc=usb:0/140000/0/2 22:56:10.157 (ports 5): usb_remove: usb:0/140000/0/2 22:56:10.157 (ports 5): nothing new, skipping HID & Ports enum 22:56:10.157 (ports 6): remove: loc=usb:0/140000/0/2 22:56:10.157 (ports 6): usb_remove: usb:0/140000/0/2 22:56:10.157 (ports 6): nothing new, skipping HID & Ports enum 22:56:10.169 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:10.170 (ports 6): nothing new, skipping HID & Ports enum 22:56:10.170 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:56:10.171 (ports 6): nothing new, skipping HID & Ports enum 22:56:10.172 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:10.173 (ports 5): nothing new, skipping HID & Ports enum 22:56:10.174 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 22:56:10.175 (ports 5): nothing new, skipping HID & Ports enum 22:56:10.856 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:10.857 (ports 6): nothing new, skipping HID & Ports enum 22:56:10.859 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:10.860 (ports 5): nothing new, skipping HID & Ports enum 22:56:10.898 (ports 6): WM_DEVICECHANGE DBT_DEVICEARRIVAL 22:56:10.898 (ports 5): WM_DEVICECHANGE DBT_DEVICEARRIVAL 22:56:10.901 (ports 6): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#0001b324#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:56:10.901 (ports 6): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 22:56:10.901 (ports 6): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0102 22:56:10.901 (ports 6): found_usb_device, devinst=0000001e 22:56:10.901 (ports 6): add: loc=usb:0/140000/0/2, class=HID, vid=16C0, pid=0478, ver=0102, serial=0001b324, dev=\\?\usb#vid_16c0&pid_0478#0001b324#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:56:10.901 (ports 5): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#0001b324#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:56:10.901 (ports 5): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 22:56:10.901 (ports 5): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0102 22:56:10.901 (ports 5): found_usb_device, devinst=0000001e 22:56:10.901 (ports 5): add: loc=usb:0/140000/0/2, class=HID, vid=16C0, pid=0478, ver=0102, serial=0001b324, dev=\\?\usb#vid_16c0&pid_0478#0001b324#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 22:56:10.902 (ports 5): hiddev_from_devinst_list: iface=0 22:56:10.902 (ports 6): hiddev_from_devinst_list: iface=0 22:56:10.905 (ports 5): found_usb_device complete 22:56:10.905 (ports 6): found_usb_device complete 22:56:10.921 (ports 5): hid, found devinst=0000001D 22:56:10.921 (ports 5): usb_add: usb:0/140000/0/2 [no_device] (Teensy LC) Bootloader 22:56:10.922 (ports 6): hid, found devinst=0000001D 22:56:10.922 (ports 6): usb_add: usb:0/140000/0/2 [no_device] (Teensy LC) Bootloader 22:56:10.924 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:10.937 (ports 6): update_usb_device, devinst list change, old had 1, new has 2 22:56:10.937 (ports 6): hiddev_from_devinst_list: iface=0 22:56:10.953 (ports 6): hid, found devinst=0000001D 22:56:10.953 (ports 6): usb_add: usb:0/140000/0/2 [no_device] (Teensy LC) Bootloader 22:56:10.978 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:10.988 (ports 5): update_usb_device, devinst list change, old had 1, new has 2 22:56:10.988 (ports 5): hiddev_from_devinst_list: iface=0 22:56:11.001 (ports 5): hid, found devinst=0000001F 22:56:11.001 (ports 5): hid, path=\\?\hid#vid_16c0&pid_0478#7&8c23b88&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:56:11.001 (ports 5): hid, opened handle 22:56:11.001 (ports 5): devinst=0000001F, location=usb:0/140000/0/2 22:56:11.001 (ports 5): vid=16C0, pid=0478, ver=0102, usepage=FF9C, use=0020 22:56:11.001 (ports 5): devpath=\\?\hid#vid_16c0&pid_0478#7&8c23b88&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:56:11.001 (ports 5): hid, found devinst=0000001D 22:56:11.002 (ports 5): usb_add: usb:0/140000/0/2 hid#vid_16c0&pid_0478 (Teensy LC) Bootloader 22:56:11.030 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:11.045 (ports 6): hid, found devinst=0000001F 22:56:11.045 (ports 6): hid, path=\\?\hid#vid_16c0&pid_0478#7&8c23b88&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:56:11.045 (ports 6): hid, opened handle 22:56:11.045 (ports 6): devinst=0000001F, location=usb:0/140000/0/2 22:56:11.045 (ports 6): vid=16C0, pid=0478, ver=0102, usepage=FF9C, use=0020 22:56:11.045 (ports 6): devpath=\\?\hid#vid_16c0&pid_0478#7&8c23b88&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030} 22:56:11.046 (ports 6): hid, found devinst=0000001D 22:56:11.046 (ports 6): usb_add: usb:0/140000/0/2 hid#vid_16c0&pid_0478 (Teensy LC) Bootloader 22:56:11.063 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 22:56:11.074 (ports 5): nothing new, skipping HID & Ports enum 23:00:19.065 (post_compile 1): Begin, version=1.44-beta1, high-res time 23:00:21.194 (loader): Teensy Loader 1.44-beta1, begin program 23:00:21.294 (loader): Listening for remote control on port 3149 23:00:21.294 (loader): initialized, showing main window 23:00:21.368 (loader): remote connection 1260 opened 23:00:21.369 (loader): remote cmd from 1260: "comment: Teensyduino 1.44-beta1 - WINDOWS (teensy_post_compile)" 23:00:21.370 (post_compile 1): Sending command: comment: Teensyduino 1.44-beta1 - WINDOWS (teensy_post_compile) 23:00:21.370 (loader): remote cmd from 1260: "status" 23:00:21.434 (loader): HID/win32: vid:16C0 pid:0478 ver:0102 23:00:21.434 (loader): HID/win32: usage_page:FF9C, usage:0020 23:00:21.435 (loader): Device came online, code_size = 63488 23:00:21.435 (loader): Board is: Teensy LC (MKL26Z64), version 1.02 23:00:21.435 (loader): set background IMG_ONLINE 23:00:21.440 (post_compile 1): Status: 0, 0, 1, 1, 0, 0, T:\TEMP\arduino_build_384576\, Basic.ino.hex 23:00:21.440 (post_compile 1): Sending command: dir:T:\TEMP\arduino_build_182061\ 23:00:21.440 (loader): remote cmd from 1260: "dir:T:\TEMP\arduino_build_182061\" 23:00:21.440 (loader): remote cmd from 1260: "file:pianoTR5.ino.hex" 23:00:21.441 (post_compile 1): Sending command: file:pianoTR5.ino.hex 23:00:21.446 (loader): File "pianoTR5.ino.hex". 27060 bytes, 43% used 23:00:21.452 (loader): remote cmd from 1260: "status" 23:00:21.453 (loader): HID/win32: HidD_GetPreparsedData ok, device still online :-) 23:00:21.455 (post_compile 1): Status: 1, 0, 1, 1, 0, 0, T:\TEMP\arduino_build_182061\, pianoTR5.ino.hex 23:00:21.455 (post_compile 1): Disconnect 23:00:21.467 (loader): remote connection 1260 closed 23:00:22.078 (post_compile 2): Begin, version=1.44-beta1, high-res time 23:00:22.163 (loader): remote connection 1260 opened 23:00:22.163 (loader): remote cmd from 1260: "comment: Teensyduino 1.44-beta1 - WINDOWS (teensy_post_compile)" 23:00:22.163 (loader): remote cmd from 1260: "status" 23:00:22.163 (loader): remote cmd from 1260: "dir:T:\TEMP\arduino_build_182061\" 23:00:22.163 (loader): remote cmd from 1260: "file:pianoTR5.ino.hex" 23:00:22.163 (loader): File "pianoTR5.ino.hex". 27060 bytes, 43% used 23:00:22.166 (post_compile 2): Sending command: comment: Teensyduino 1.44-beta1 - WINDOWS (teensy_post_compile) 23:00:22.166 (post_compile 2): Status: 1, 0, 1, 1, 0, 0, T:\TEMP\arduino_build_182061\, pianoTR5.ino.hex 23:00:22.166 (post_compile 2): Sending command: dir:T:\TEMP\arduino_build_182061\ 23:00:22.166 (post_compile 2): Sending command: file:pianoTR5.ino.hex 23:00:22.173 (loader): remote cmd from 1260: "status" 23:00:22.177 (post_compile 2): Status: 1, 0, 1, 1, 0, 0, T:\TEMP\arduino_build_182061\, pianoTR5.ino.hex 23:00:22.177 (post_compile 2): Disconnect 23:00:22.193 (loader): remote connection 1260 closed 23:00:22.193 (loader): remote connection 1316 opened 23:00:22.193 (post_compile 3): Running teensy_reboot: "T:\arduino_1.8.6\hardware\teensy\..\tools\teensy_reboot.exe" teensy_reboot.exe "-board=TEENSYLC" "-port=usb:0/140000/0/2" "-portlabel=hid#vid_16c0&pid_0478 (Teensy LC) Bootloader" "-portprotocol=Teensy" 23:00:22.352 (reboot 4): Begin, version=1.44-beta1, high-res time 23:00:22.352 (reboot 4): location = usb:0/140000/0/2 23:00:22.352 (reboot 4): portlabel = hid#vid_16c0&pid_0478 (Teensy LC) Bootloader 23:00:22.352 (reboot 4): portprotocol = Teensy 23:00:22.352 (reboot 4): Only location usb:0/140000/0/2 will be tried 23:00:22.352 (reboot 4): LoadLibrary cfgmgr32 ok 23:00:22.352 (reboot 4): LoadLibrary ntdll ok 23:00:22.353 (loader): remote connection 1260 opened 23:00:22.353 (loader): remote cmd from 1260: "show:arduino_attempt_reboot" 23:00:22.353 (loader): got request to show arduino rebooting message 23:00:22.354 (reboot 4): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#0001b324#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:22.354 (reboot 4): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:00:22.354 (reboot 4): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0102 23:00:22.354 (reboot 4): found_usb_device, devinst=00000005 23:00:22.354 (reboot 4): add: loc=usb:0/140000/0/2, class=HID, vid=16C0, pid=0478, ver=0102, serial=0001b324, dev=\\?\usb#vid_16c0&pid_0478#0001b324#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:22.354 (reboot 4): hiddev_from_devinst_list: iface=0 23:00:22.356 (reboot 4): found_usb_device complete 23:00:22.356 (reboot 4): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:22.356 (reboot 4): found_usb_device, loc=usb:0/140000/0/6/1 Port_#0001.Hub_#0005 23:00:22.356 (reboot 4): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0103 23:00:22.356 (reboot 4): found_usb_device, devinst=00000006 23:00:22.356 (reboot 4): add: loc=usb:0/140000/0/6/1, class=HID, vid=16C0, pid=0478, ver=0103, serial=00032347, dev=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:22.356 (reboot 4): hiddev_from_devinst_list: iface=0 23:00:22.357 (reboot 4): found_usb_device complete 23:00:22.357 (reboot 4): hid, found devinst=0000000B 23:00:22.357 (reboot 4): hid, found devinst=00000018 23:00:22.360 (reboot 4): found Teensy Loader, version 1.44 23:00:22.360 (reboot 4): Sending command: show:arduino_attempt_reboot 23:00:22.363 (loader): remote cmd from 1260: "comment: Teensyduino 1.44-beta1 - WINDOWS (teensy_reboot)" 23:00:22.363 (loader): remote cmd from 1260: "status" 23:00:22.363 (loader): remote cmd from 1260: "auto:on" 23:00:22.363 (loader): File "pianoTR5.ino.hex". 27060 bytes, 43% used 23:00:22.363 (loader): elf size appears to be 63488 23:00:22.363 (loader): elf binary data matches hex file 23:00:22.363 (loader): Code size from .elf file = 63488 23:00:22.363 (loader): begin operation 23:00:22.363 (reboot 4): Sending command: comment: Teensyduino 1.44-beta1 - WINDOWS (teensy_reboot) 23:00:22.364 (reboot 4): Status: 1, 0, 1, 1, 0, 0, T:\TEMP\arduino_build_182061\, pianoTR5.ino.hex 23:00:22.364 (reboot 4): send: auto:on 23:00:22.364 (reboot 4): Sending command: auto:on 23:00:22.364 (reboot 4): Disconnect 23:00:22.384 (loader): remote connection 1260 closed 23:00:22.387 (loader): remote connection 1316 closed 23:00:22.394 (loader): flash, block=0, bs=512, auto=1 23:00:22.399 (loader): flash, block=1, bs=512, auto=1 23:00:22.402 (loader): flash, block=2, bs=512, auto=1 23:00:22.403 (loader): flash, block=3, bs=512, auto=1 23:00:22.404 (loader): flash, block=4, bs=512, auto=1 23:00:22.405 (loader): flash, block=5, bs=512, auto=1 23:00:22.603 (loader): flash, block=6, bs=512, auto=1 23:00:22.603 (loader): flash, block=7, bs=512, auto=1 23:00:22.603 (loader): flash, block=8, bs=512, auto=1 23:00:22.623 (loader): flash, block=9, bs=512, auto=1 23:00:22.623 (loader): flash, block=10, bs=512, auto=1 23:00:22.643 (loader): flash, block=11, bs=512, auto=1 23:00:22.643 (loader): flash, block=12, bs=512, auto=1 23:00:22.643 (loader): flash, block=13, bs=512, auto=1 23:00:22.663 (loader): flash, block=14, bs=512, auto=1 23:00:22.663 (loader): flash, block=15, bs=512, auto=1 23:00:22.683 (loader): flash, block=16, bs=512, auto=1 23:00:22.683 (loader): flash, block=17, bs=512, auto=1 23:00:22.683 (loader): flash, block=18, bs=512, auto=1 23:00:22.703 (loader): flash, block=19, bs=512, auto=1 23:00:22.703 (loader): flash, block=20, bs=512, auto=1 23:00:22.723 (loader): flash, block=21, bs=512, auto=1 23:00:22.723 (loader): flash, block=22, bs=512, auto=1 23:00:22.723 (loader): flash, block=23, bs=512, auto=1 23:00:22.743 (loader): flash, block=24, bs=512, auto=1 23:00:22.743 (loader): flash, block=25, bs=512, auto=1 23:00:22.763 (loader): flash, block=26, bs=512, auto=1 23:00:22.763 (loader): flash, block=27, bs=512, auto=1 23:00:22.763 (loader): flash, block=28, bs=512, auto=1 23:00:22.783 (loader): flash, block=29, bs=512, auto=1 23:00:22.783 (loader): flash, block=30, bs=512, auto=1 23:00:22.783 (loader): flash, block=31, bs=512, auto=1 23:00:22.803 (loader): flash, block=32, bs=512, auto=1 23:00:22.803 (loader): flash, block=33, bs=512, auto=1 23:00:22.823 (loader): flash, block=34, bs=512, auto=1 23:00:22.823 (loader): flash, block=35, bs=512, auto=1 23:00:22.823 (loader): flash, block=36, bs=512, auto=1 23:00:22.843 (loader): flash, block=37, bs=512, auto=1 23:00:22.843 (loader): flash, block=38, bs=512, auto=1 23:00:22.863 (loader): flash, block=39, bs=512, auto=1 23:00:22.863 (loader): flash, block=40, bs=512, auto=1 23:00:22.863 (loader): flash, block=41, bs=512, auto=1 23:00:22.883 (loader): flash, block=42, bs=512, auto=1 23:00:22.883 (loader): flash, block=43, bs=512, auto=1 23:00:22.903 (loader): flash, block=44, bs=512, auto=1 23:00:22.903 (loader): flash, block=45, bs=512, auto=1 23:00:22.903 (loader): flash, block=46, bs=512, auto=1 23:00:22.923 (loader): flash, block=47, bs=512, auto=1 23:00:22.923 (loader): flash, block=48, bs=512, auto=1 23:00:22.943 (loader): flash, block=49, bs=512, auto=1 23:00:22.943 (loader): flash, block=50, bs=512, auto=1 23:00:22.943 (loader): flash, block=51, bs=512, auto=1 23:00:22.963 (loader): flash, block=52, bs=512, auto=1 23:00:22.963 (loader): sending reboot 23:00:22.963 (loader): begin wait_until_offline 23:00:23.016 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:00:23.016 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:00:23.023 (loader): offline, waited 1 23:00:23.023 (loader): end operation, total time = 0.660 seconds 23:00:23.023 (loader): set background IMG_REBOOT_OK 23:00:23.023 (loader): redraw timer set, image 14 to show for 1200 ms 23:00:23.023 (loader): remote connection 1324 opened 23:00:23.023 (loader): remote connection 1320 opened 23:00:23.030 (ports 5): remove: loc=usb:0/140000/0/2 23:00:23.030 (ports 5): usb_remove: usb:0/140000/0/2 23:00:23.030 (ports 5): nothing new, skipping HID & Ports enum 23:00:23.030 (ports 6): remove: loc=usb:0/140000/0/2 23:00:23.030 (ports 6): usb_remove: usb:0/140000/0/2 23:00:23.030 (ports 6): nothing new, skipping HID & Ports enum 23:00:23.031 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:00:23.031 (ports 6): nothing new, skipping HID & Ports enum 23:00:23.032 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:00:23.033 (ports 5): nothing new, skipping HID & Ports enum 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B410 ver:0131 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B406 ver:0100 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B406 ver:0100 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B410 ver:0131 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B410 ver:0131 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B406 ver:0100 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B406 ver:0100 23:00:23.033 (loader): HID/win32: vid:1B80 pid:B410 ver:0131 23:00:23.050 (loader): HID/win32: vid:16C0 pid:0478 ver:0103 23:00:23.050 (loader): HID/win32: usage_page:FF9C, usage:0022 23:00:23.050 (loader): Device came online, code_size = 1048576 23:00:23.050 (loader): Board is: Teensy 3.6 (MK66FX1M0), version 1.03 23:00:23.050 (loader): File "pianoTR5.ino.hex". 27060 bytes, 3% used 23:00:23.060 (loader): reboot too soon timer still running, oh no! 23:00:23.264 (loader): HID/win32: HidD_GetPreparsedData ok, device still online :-) 23:00:23.403 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:00:23.403 (ports 6): nothing new, skipping HID & Ports enum 23:00:23.404 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:00:23.405 (ports 5): nothing new, skipping HID & Ports enum 23:00:23.406 (ports 5): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:00:23.406 (ports 6): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:00:23.407 (ports 5): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:23.407 (ports 5): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:00:23.407 (ports 5): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 23:00:23.407 (ports 5): found_usb_device, devinst=00000005 23:00:23.407 (ports 5): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:23.407 (ports 6): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:23.407 (ports 6): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:00:23.407 (ports 6): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 23:00:23.407 (ports 6): found_usb_device, devinst=00000005 23:00:23.407 (ports 5): comport_from_devinst_list attempt 23:00:23.407 (ports 5): found Ports in classguid_list at index=0 23:00:23.407 (ports 6): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:00:23.407 (ports 6): comport_from_devinst_list attempt 23:00:23.407 (ports 6): found Ports in classguid_list at index=0 23:00:23.407 (ports 5): port COM11 found from devnode 23:00:23.407 (ports 5): found_usb_device complete 23:00:23.407 (ports 6): port COM11 found from devnode 23:00:23.407 (ports 6): found_usb_device complete 23:00:23.408 (ports 6): hid, found devinst=0000001D 23:00:23.408 (ports 5): hid, found devinst=0000001D 23:00:23.408 (ports 6): usb_add: usb:0/140000/0/2 COM11 (Teensy LC) Serial 23:00:23.408 (ports 5): usb_add: usb:0/140000/0/2 COM11 (Teensy LC) Serial 23:00:23.429 (ports 6): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:00:23.430 (ports 6): nothing new, skipping HID & Ports enum 23:00:23.432 (ports 5): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:00:23.433 (ports 5): nothing new, skipping HID & Ports enum 23:00:23.490 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:00:23.492 (ports 6): nothing new, skipping HID & Ports enum 23:00:23.493 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:00:23.494 (ports 5): nothing new, skipping HID & Ports enum 23:00:24.224 (loader): redraw, image 10 23:00:24.224 (loader): set background IMG_ONLINE 23:01:35.038 (serialmon 7): Begin, version=1.44-beta1, high-res time 23:01:35.038 (serialmon 7): listening for location: usb:0/140000/0/2 23:01:35.038 (serialmon 7): LoadLibrary cfgmgr32 ok 23:01:35.038 (serialmon 7): LoadLibrary ntdll ok 23:01:35.043 (serialmon 7): callback 0024 23:01:35.043 (serialmon 7): callback 0081 23:01:35.046 (serialmon 7): callback 0083 23:01:35.048 (serialmon 7): hWnd = 1713926 23:01:35.048 (serialmon 7): loop stdin, ready=2097151 23:01:35.050 (serialmon 7): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:35.050 (serialmon 7): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:01:35.050 (serialmon 7): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 23:01:35.050 (serialmon 7): found_usb_device, devinst=00000004 23:01:35.050 (serialmon 7): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:35.050 (serialmon 7): comport_from_devinst_list attempt 23:01:35.050 (serialmon 7): found Ports in classguid_list at index=0 23:01:35.050 (serialmon 7): port COM11 found from devnode 23:01:35.050 (serialmon 7): found_usb_device complete 23:01:35.050 (serialmon 7): found_usb_device, id=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:35.050 (serialmon 7): found_usb_device, loc=usb:0/140000/0/6/1 Port_#0001.Hub_#0005 23:01:35.050 (serialmon 7): found_usb_device, hwid=USB\VID_16C0&PID_0478&REV_0103 23:01:35.050 (serialmon 7): found_usb_device, devinst=00000006 23:01:35.050 (serialmon 7): add: loc=usb:0/140000/0/6/1, class=HID, vid=16C0, pid=0478, ver=0103, serial=00032347, dev=\\?\usb#vid_16c0&pid_0478#00032347#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:35.050 (serialmon 7): hiddev_from_devinst_list: iface=0 23:01:35.051 (serialmon 7): found_usb_device complete 23:01:35.052 (serialmon 7): hid, found devinst=0000000C 23:01:35.052 (serialmon 7): usb_add: usb:0/140000/0/6/1 23:01:35.052 (serialmon 7): ignoring partial USB device discovery (Windows XP issue?) 23:01:35.052 (serialmon 7): usb_add: usb:0/140000/0/2 23:01:35.052 (serialmon 7): translate "COM11" -> "\\.\COM11" 23:01:35.126 (serialmon 7): GetDefaultCommConfig success 23:01:35.199 (serialmon 7): SetDefaultCommConfig success 23:01:35.199 (serialmon 7): Opened \\.\COM11 Serial 23:01:35.202 (ports 6): callback 001A 23:01:35.202 (ports 5): callback 001A 23:01:38.217 (loader): remote connection 1356 opened 23:01:38.219 (serialmon 7): callback 001A 23:01:45.927 (serialmon 7): teensy read ov error 23:01:45.927 (serialmon 7): teensy read error 23:01:45.929 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:01:45.929 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:01:45.929 (serialmon 7): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:01:45.930 (ports 6): remove: loc=usb:0/140000/0/2 23:01:45.930 (ports 6): usb_remove: usb:0/140000/0/2 23:01:45.930 (ports 6): nothing new, skipping HID & Ports enum 23:01:45.930 (ports 5): remove: loc=usb:0/140000/0/2 23:01:45.930 (ports 5): usb_remove: usb:0/140000/0/2 23:01:45.930 (ports 5): nothing new, skipping HID & Ports enum 23:01:45.930 (serialmon 7): remove: loc=usb:0/140000/0/2 23:01:45.930 (serialmon 7): usb_remove: usb:0/140000/0/2 23:01:45.930 (serialmon 7): Disconnect \\.\COM11 23:01:45.931 (serialmon 7): hid, found devinst=0000000C 23:01:45.945 (serialmon 7): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:45.947 (serialmon 7): hid, found devinst=0000000C 23:01:45.948 (serialmon 7): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:01:45.948 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:45.949 (serialmon 7): hid, found devinst=0000000C 23:01:45.949 (ports 6): nothing new, skipping HID & Ports enum 23:01:45.950 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:45.951 (ports 6): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:01:45.951 (ports 5): nothing new, skipping HID & Ports enum 23:01:45.951 (ports 6): nothing new, skipping HID & Ports enum 23:01:45.952 (ports 5): WM_DEVICECHANGE DBT_DEVICEREMOVECOMPLETE 23:01:45.953 (ports 5): nothing new, skipping HID & Ports enum 23:01:47.629 (ports 6): purge, name=COM11 (Teensy LC) Serial, loc=usb:0/140000/0/2, age=1.699 sec 23:01:47.629 (ports 5): purge, name=COM11 (Teensy LC) Serial, loc=usb:0/140000/0/2, age=1.699 sec 23:01:47.812 (serialmon 7): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:47.813 (serialmon 7): hid, found devinst=0000000C 23:01:47.814 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:47.815 (ports 6): nothing new, skipping HID & Ports enum 23:01:47.816 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:47.817 (ports 5): nothing new, skipping HID & Ports enum 23:01:47.824 (ports 5): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:01:47.824 (ports 6): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:01:47.825 (serialmon 7): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:01:47.826 (ports 5): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:47.826 (ports 5): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:01:47.826 (ports 5): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 23:01:47.826 (ports 5): found_usb_device, devinst=00000005 23:01:47.826 (ports 5): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:47.826 (ports 5): comport_from_devinst_list attempt 23:01:47.826 (ports 5): found Ports in classguid_list at index=0 23:01:47.826 (ports 5): port COM11 found from devnode 23:01:47.826 (ports 5): found_usb_device complete 23:01:47.826 (ports 6): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:47.826 (ports 6): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:01:47.826 (ports 6): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 23:01:47.826 (ports 6): found_usb_device, devinst=00000005 23:01:47.826 (ports 6): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:47.826 (ports 6): comport_from_devinst_list attempt 23:01:47.826 (ports 6): found Ports in classguid_list at index=0 23:01:47.826 (ports 6): port COM11 found from devnode 23:01:47.826 (ports 6): found_usb_device complete 23:01:47.827 (serialmon 7): found_usb_device, id=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:47.827 (serialmon 7): found_usb_device, loc=usb:0/140000/0/2 Port_#0002.Hub_#0001 23:01:47.827 (serialmon 7): found_usb_device, hwid=USB\VID_16C0&PID_0483&REV_0273 23:01:47.827 (serialmon 7): found_usb_device, devinst=00000004 23:01:47.827 (serialmon 7): add: loc=usb:0/140000/0/2, class=Ports, vid=16C0, pid=0483, ver=0273, serial=1113960, dev=\\?\usb#vid_16c0&pid_0483#1113960#{a5dcbf10-6530-11d2-901f-00c04fb951ed} 23:01:47.827 (serialmon 7): comport_from_devinst_list attempt 23:01:47.827 (serialmon 7): found Ports in classguid_list at index=0 23:01:47.827 (serialmon 7): port COM11 found from devnode 23:01:47.827 (serialmon 7): found_usb_device complete 23:01:47.827 (ports 5): hid, found devinst=0000001D 23:01:47.827 (ports 5): usb_add: usb:0/140000/0/2 COM11 (Teensy LC) Serial 23:01:47.827 (ports 6): hid, found devinst=0000001D 23:01:47.827 (ports 6): usb_add: usb:0/140000/0/2 COM11 (Teensy LC) Serial 23:01:47.828 (serialmon 7): hid, found devinst=0000000C 23:01:47.828 (serialmon 7): usb_add: usb:0/140000/0/2 23:01:47.828 (serialmon 7): translate "COM11" -> "\\.\COM11" 23:01:47.969 (serialmon 7): GetDefaultCommConfig success 23:01:48.080 (serialmon 7): SetDefaultCommConfig success 23:01:48.080 (serialmon 7): Opened \\.\COM11 Serial 23:01:48.083 (ports 5): callback 001A 23:01:48.083 (ports 6): callback 001A 23:01:48.085 (serialmon 7): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:01:48.087 (serialmon 7): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:48.094 (serialmon 7): callback 001A 23:01:48.102 (serialmon 7): hid, found devinst=0000000C 23:01:48.103 (serialmon 7): found new COM port, devinst=00000004 23:01:48.103 (serialmon 7): portname=COM11 23:01:48.103 (serialmon 7): usb_add: usb:0/140000/0/2 23:01:48.156 (ports 6): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:01:48.156 (ports 6): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:48.158 (ports 6): nothing new, skipping HID & Ports enum 23:01:48.160 (ports 5): WM_DEVICECHANGE DBT_DEVICEARRIVAL 23:01:48.162 (ports 5): nothing new, skipping HID & Ports enum 23:01:48.163 (ports 5): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:48.164 (ports 5): nothing new, skipping HID & Ports enum 23:01:58.639 (serialmon 7): WM_DEVICECHANGE DBT_DEVNODES_CHANGED 23:01:58.640 (serialmon 7): hid, found devinst=0000000C 23:07:23.033 (loader): Verbose Info event