2021-04-07 14:16:04.714 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2021-04-03_13-00-19.e6639e5. 2021-04-07 14:16:04.715 Scripting TRACE: Scripting.on Startup 2021-04-07 14:16:04.836 AbstractBroadcastingCamera TRACE: Camera Top Camera thread 36 started. 2021-04-07 14:16:04.837 CameraView DEBUG: Failed to load camera specific reticle, checking default. 2021-04-07 14:16:04.838 CameraView DEBUG: No reticle preference found. 2021-04-07 14:16:06.214 AbstractBroadcastingCamera TRACE: Camera Up Camera thread 38 started. 2021-04-07 14:16:15.334 ReferenceMachine DEBUG: setEnabled(true) 2021-04-07 14:16:18.517 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G21 ; Set millimeters mode, 10500)... 2021-04-07 14:16:18.518 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G90 ; Set absolute positioning mode, 10500)... 2021-04-07 14:16:18.518 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M82 ; Set absolute mode for extruder, 10500)... 2021-04-07 14:16:18.518 NullDriver DEBUG: setEnabled(true) 2021-04-07 14:16:18.518 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G21 ; Set millimeters mode 2021-04-07 14:16:18.523 ReferenceActuator DEBUG: DRAGPIN.actuate(false) 2021-04-07 14:16:18.523 SystemLogger ERROR: Exception in thread "AWT-EventQueue-0" java.lang.NullPointerException 2021-04-07 14:16:18.523 SystemLogger ERROR: at com.kitfox.svg.SVGDiagram.render(SVGDiagram.java:111) 2021-04-07 14:16:18.523 SystemLogger ERROR: at com.formdev.flatlaf.extras.FlatSVGIcon.paintSvg(FlatSVGIcon.java:340) 2021-04-07 14:16:18.524 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M817 ; M816/817, 10500)... 2021-04-07 14:16:18.524 SystemLogger ERROR: at com.formdev.flatlaf.extras.FlatSVGIcon.paintIcon(FlatSVGIcon.java:312) 2021-04-07 14:16:18.524 SystemLogger ERROR: at com.formdev.flatlaf.extras.FlatSVGIcon.lambda$getImage$0(FlatSVGIcon.java:374) 2021-04-07 14:16:18.524 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G4P50 ; 100ms delay to deploy/retract, 10500)... 2021-04-07 14:16:18.524 SystemLogger ERROR: at com.formdev.flatlaf.util.MultiResolutionImageSupport.create(MultiResolutionImageSupport.java:83) 2021-04-07 14:16:18.524 SystemLogger ERROR: at com.formdev.flatlaf.extras.FlatSVGIcon.getImage(FlatSVGIcon.java:381) 2021-04-07 14:16:18.524 SystemLogger ERROR: at javax.swing.LookAndFeel.getDisabledIcon(LookAndFeel.java:557) 2021-04-07 14:16:18.524 SystemLogger ERROR: at javax.swing.AbstractButton.getDisabledIcon(AbstractButton.java:653) 2021-04-07 14:16:18.524 SystemLogger ERROR: at com.apple.laf.AquaButtonUI.paintIcon(AquaButtonUI.java:363) 2021-04-07 14:16:18.525 SystemLogger ERROR: at com.apple.laf.AquaButtonUI.paint(AquaButtonUI.java:304) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.plaf.ComponentUI.update(ComponentUI.java:161) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent.paintComponent(JComponent.java:780) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent.paint(JComponent.java:1056) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent.paintChildren(JComponent.java:889) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent.paint(JComponent.java:1065) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent.paintToOffscreen(JComponent.java:5210) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.RepaintManager$PaintManager.paintDoubleBuffered(RepaintManager.java:1579) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.RepaintManager$PaintManager.paint(RepaintManager.java:1502) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.RepaintManager.paint(RepaintManager.java:1272) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent._paintImmediately(JComponent.java:5158) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.JComponent.paintImmediately(JComponent.java:4969) 2021-04-07 14:16:18.525 SystemLogger ERROR: at javax.swing.RepaintManager$4.run(RepaintManager.java:831) 2021-04-07 14:16:18.526 SystemLogger ERROR: at javax.swing.RepaintManager$4.run(RepaintManager.java:814) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.security.AccessController.doPrivileged(Native Method) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:80) 2021-04-07 14:16:18.526 SystemLogger ERROR: at javax.swing.RepaintManager.paintDirtyRegions(RepaintManager.java:814) 2021-04-07 14:16:18.526 SystemLogger ERROR: at javax.swing.RepaintManager.paintDirtyRegions(RepaintManager.java:789) 2021-04-07 14:16:18.526 SystemLogger ERROR: at javax.swing.RepaintManager.prePaintDirtyRegions(RepaintManager.java:738) 2021-04-07 14:16:18.526 SystemLogger ERROR: at javax.swing.RepaintManager.access$1200(RepaintManager.java:64) 2021-04-07 14:16:18.526 SystemLogger ERROR: at javax.swing.RepaintManager$ProcessingRunnable.run(RepaintManager.java:1732) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.awt.event.InvocationEvent.dispatch(InvocationEvent.java:311) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.awt.EventQueue.dispatchEventImpl(EventQueue.java:756) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.awt.EventQueue.access$500(EventQueue.java:97) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.awt.EventQueue$3.run(EventQueue.java:709) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.awt.EventQueue$3.run(EventQueue.java:703) 2021-04-07 14:16:18.526 SystemLogger ERROR: at java.security.AccessController.doPrivileged(Native Method) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(ProtectionDomain.java:80) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventQueue.dispatchEvent(EventQueue.java:726) 2021-04-07 14:16:18.527 SystemLogger ERROR: at org.openpnp.gui.MainFrame$2.dispatchEvent(MainFrame.java:462) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpOneEventForFilters(EventDispatchThread.java:201) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEventsForFilter(EventDispatchThread.java:116) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEventsForHierarchy(EventDispatchThread.java:105) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:101) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEvents(EventDispatchThread.java:93) 2021-04-07 14:16:18.527 SystemLogger ERROR: at java.awt.EventDispatchThread.run(EventDispatchThread.java:82) 2021-04-07 14:16:18.615 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:18.615 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G21 ; Set millimeters mode 2021-04-07 14:16:18.615 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G90 ; Set absolute positioning mode 2021-04-07 14:16:18.847 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:18.847 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G90 ; Set absolute positioning mode 2021-04-07 14:16:18.847 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M82 ; Set absolute mode for extruder 2021-04-07 14:16:19.078 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:19.079 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M82 ; Set absolute mode for extruder 2021-04-07 14:16:19.079 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M817 ; M816/817 2021-04-07 14:16:19.310 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:19.311 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M817 ; M816/817 2021-04-07 14:16:19.311 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G4P50 ; 100ms delay to deploy/retract 2021-04-07 14:16:19.542 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:19.542 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G4P50 ; 100ms delay to deploy/retract 2021-04-07 14:16:19.543 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:16:19.544 ReferenceActuator DEBUG: VAC.actuate(true) 2021-04-07 14:16:19.544 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M808 ; M808/809, 10500)... 2021-04-07 14:16:19.545 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M808 ; M808/809 2021-04-07 14:16:19.774 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:19.774 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M808 ; M808/809 2021-04-07 14:16:19.774 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:16:45.876 ReferenceMachine DEBUG: homing machine 2021-04-07 14:16:45.876 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G28 ; Home all axes, -1)... 2021-04-07 14:16:45.876 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G28 ; Home all axes 2021-04-07 14:16:45.877 NullDriver DEBUG: home() 2021-04-07 14:16:47.362 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:47.363 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G28 ; Home all axes 2021-04-07 14:16:47.363 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:16:47.363 ReferenceHead DEBUG: H1.home() 2021-04-07 14:16:47.364 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (48.736000, 3.112000, 0.000000, 0.000000 mm) 2021-04-07 14:16:47.364 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:16:47.364 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:16:47.365 ReferenceNozzle TRACE: N1.transformToHeadLocation((0.000000, 0.000000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:47.365 ReferenceNozzle TRACE: N1.transformToHeadLocation((0.000000, 0.000000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:47.365 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:16:47.365 ReferenceNozzle TRACE: N2.transformToHeadLocation((0.000000, 0.000000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:47.365 ReferenceNozzle TRACE: N2.transformToHeadLocation((0.000000, 0.000000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:47.366 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:16:47.366 AbstractHeadMountable DEBUG: Top Camera.moveTo((48.736000, 3.112000, NaN, 0.000000 mm), 1.0) 2021-04-07 14:16:47.367 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:16:47.367 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:16:47.594 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:16:47.595 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:47.595 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:16:47.595 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:16:47.595 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:16:47.595 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:16:47.608 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:16:47.608 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:16:47.608 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:16:47.608 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:16:47.608 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:16:47.608 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:16:47.609 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:16:47.609 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X48.736 Y3.112 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:16:47.609 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:16:47.826 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:47.826 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:16:47.827 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:16:48.058 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:48.058 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:16:48.058 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:16:48.290 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:48.290 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:16:48.290 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:16:48.522 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:48.522 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:16:48.522 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:16:48.753 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:48.754 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:16:48.754 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:16:48.985 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:48.985 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:16:48.986 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X48.736 Y3.112 F8000 ; Send standard Gcode move 2021-04-07 14:16:49.217 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:49.217 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X48.736 Y3.112 F8000 ; Send standard Gcode move 2021-04-07 14:16:49.217 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:16:49.681 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:49.681 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:16:49.681 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:16:49.681 AbstractHeadMountable DEBUG: Top Camera.moveTo((48.736000, 3.112000, 0.000000, 0.000000 mm), 1.0) 2021-04-07 14:16:49.682 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:16:49.682 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:16:49.682 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:49.682 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:49.682 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:16:49.682 ReferenceNozzle TRACE: N2.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:49.683 ReferenceNozzle TRACE: N2.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:16:49.683 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:16:49.683 AbstractHeadMountable DEBUG: Top Camera.moveTo((48.736000, 3.112000, NaN, 0.000000 mm), 1.0) 2021-04-07 14:16:49.683 AbstractHeadMountable DEBUG: Top Camera.moveTo((48.736000, 3.112000, 0.000000, 0.000000 mm), 1.0) 2021-04-07 14:16:49.689 ReferenceActuator DEBUG: DOWNLED.actuate(true) 2021-04-07 14:16:49.689 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M814 ; M814/815, 10500)... 2021-04-07 14:16:49.689 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M814 ; M814/815 2021-04-07 14:16:49.689 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-04-07 14:16:49.913 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:16:49.945 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-04-07 14:16:49.947 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-04-07 14:16:49.947 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-04-07 14:16:49.982 MessageBoxes DEBUG: Error: java.lang.Exception: FIDUCIAL-HOME no matches found. 2021-04-07 14:17:03.408 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:17:03.408 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.409 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.409 ReferenceDragFeeder DEBUG: feed(N1 N1) 2021-04-07 14:17:03.409 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:17:03.409 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:17:03.409 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.409 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.409 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:17:03.409 ReferenceNozzle TRACE: N2.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.409 ReferenceNozzle TRACE: N2.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.409 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:17:03.409 ReferenceDragFeeder DEBUG: First feed, running vision pre-flight. 2021-04-07 14:17:03.410 ReferenceDragFeeder DEBUG: getVisionOffsets(H1, (63.036000, 33.802000, -47.000000, 0.000000 mm)) 2021-04-07 14:17:03.410 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:17:03.410 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:17:03.410 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.410 ReferenceNozzle TRACE: N1.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.410 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:17:03.410 ReferenceNozzle TRACE: N2.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.410 ReferenceNozzle TRACE: N2.transformToHeadLocation((48.736000, 3.112000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:03.410 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:17:03.410 ReferenceDragFeeder DEBUG: Move camera to pick location. 2021-04-07 14:17:03.410 AbstractHeadMountable DEBUG: Top Camera.moveTo((63.036000, 33.802000, -47.000000, 0.000000 mm), 1.0) 2021-04-07 14:17:03.411 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:17:03.411 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M814 ; M814/815 2021-04-07 14:17:03.411 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:17:03.592 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:17:03.592 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:03.592 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:17:03.592 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:17:03.592 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:17:03.593 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:03.596 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X63.036 Y33.802 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:03.597 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:03.824 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:03.824 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:03.824 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:04.056 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:04.056 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:04.056 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:04.288 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:04.288 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:04.288 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:04.519 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:04.520 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:04.520 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:04.751 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:04.752 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:04.752 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:04.983 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:04.983 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:04.984 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X63.036 Y33.802 F8000 ; Send standard Gcode move 2021-04-07 14:17:05.215 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:05.215 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X63.036 Y33.802 F8000 ; Send standard Gcode move 2021-04-07 14:17:05.215 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:05.447 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:05.447 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:05.447 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:05.447 ReferenceDragFeeder DEBUG: Perform template match. 2021-04-07 14:17:05.448 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-04-07 14:17:05.701 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-04-07 14:17:05.703 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-04-07 14:17:05.703 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-04-07 14:17:05.707 OpenCvVisionProvider DEBUG: locateTemplateMatches certainty 13752006.000000 at 97.000000, 59.000000 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: matchX 348.0, matchY 220.0 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: centered matchX 363.0, matchY 240.5 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: offsetX -3.0, offsetY -0.5 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: negated offsetX -3.0, offsetY 0.5 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: final, in camera units offsetX -0.0, offsetY 0.0 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: visionOffsets (-0.000000, 0.000000, 0.000000, 0.000000 mm) 2021-04-07 14:17:05.721 ReferenceDragFeeder DEBUG: New drag distance with visionOffset (-4.400000, 0.000000, 0.000000, 0.000000 mm) 2021-04-07 14:17:05.721 AbstractHeadMountable DEBUG: DRAGPIN.moveTo((71.036000, 37.202000, NaN, NaN mm), 1.0) 2021-04-07 14:17:05.722 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:17:05.722 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:17:05.910 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:17:05.911 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:05.911 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:17:05.911 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:17:05.911 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:17:05.911 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:05.913 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:05.913 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:05.913 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:05.913 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:05.913 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:05.913 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:05.914 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:05.914 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X24.126 Y17.572 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:05.914 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:06.142 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:06.143 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:06.143 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:06.374 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:06.374 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:06.374 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:06.606 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:06.606 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:06.606 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:06.837 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:06.838 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:06.838 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:07.069 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:07.070 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:07.070 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:07.301 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:07.301 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:07.301 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X24.126 Y17.572 F8000 ; Send standard Gcode move 2021-04-07 14:17:07.533 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:07.533 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X24.126 Y17.572 F8000 ; Send standard Gcode move 2021-04-07 14:17:07.533 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:07.765 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:07.765 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:07.765 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:07.765 ReferenceActuator DEBUG: DRAGPIN.actuate(true) 2021-04-07 14:17:07.765 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M816 ; M816/817, 10500)... 2021-04-07 14:17:07.765 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G4P50 ; 100ms delay to deploy/retract, 10500)... 2021-04-07 14:17:07.765 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M816 ; M816/817 2021-04-07 14:17:07.997 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:07.997 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M816 ; M816/817 2021-04-07 14:17:07.997 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G4P50 ; 100ms delay to deploy/retract 2021-04-07 14:17:08.228 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:08.229 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G4P50 ; 100ms delay to deploy/retract 2021-04-07 14:17:08.229 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:08.229 AbstractHeadMountable DEBUG: DRAGPIN.moveTo((71.036000, 37.202000, -47.000000, 0.000000 mm), 1.0) 2021-04-07 14:17:08.229 ActuatorInterlockMonitor TRACE: DRAGPIN interlock masked by conditionalActuator DRAGPIN being true 2021-04-07 14:17:08.230 ActuatorInterlockMonitor TRACE: DRAGPIN interlock masked by conditionalActuator DRAGPIN being true 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 Z-47.000 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:08.231 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:08.460 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:08.461 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:08.461 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:08.692 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:08.692 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:08.692 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:08.924 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:08.924 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:08.924 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:09.156 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:09.156 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:09.156 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:09.388 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:09.388 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:09.388 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:09.620 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:09.620 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:09.620 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 Z-47.000 F8000 ; Send standard Gcode move 2021-04-07 14:17:09.852 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << Error: Soft Endstop Z was exceeded - entire move ignored 2021-04-07 14:17:09.852 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:09.852 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 Z-47.000 F8000 ; Send standard Gcode move 2021-04-07 14:17:09.852 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:10.083 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:10.083 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:10.084 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:10.084 AbstractHeadMountable DEBUG: DRAGPIN.moveTo((75.436000, 37.202000, -47.000000, 0.000000 mm), 0.25) 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X28.526 F2000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:10.088 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:10.315 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:10.315 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:10.316 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:10.547 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:10.547 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:10.547 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:10.779 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:10.779 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:10.779 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:11.011 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:11.011 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:11.011 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:11.243 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:11.243 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:11.243 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:11.474 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:11.475 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:11.475 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X28.526 F2000 ; Send standard Gcode move 2021-04-07 14:17:11.706 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:11.706 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X28.526 F2000 ; Send standard Gcode move 2021-04-07 14:17:11.707 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:11.938 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:11.938 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:11.938 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:11.939 ReferenceActuator DEBUG: LFEEDER.actuate(true) 2021-04-07 14:17:11.939 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(T2G92E0G0E5, 10500)... 2021-04-07 14:17:11.939 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> T2G92E0G0E5 2021-04-07 14:17:11.939 ReferenceActuator DEBUG: LFEEDER.actuate(false) 2021-04-07 14:17:11.939 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(T2G92E0G0E5, 10500)... 2021-04-07 14:17:11.940 AbstractHeadMountable DEBUG: DRAGPIN.moveTo((75.286000, 37.202000, -47.000000, 0.000000 mm), 0.25) 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X28.376 F2000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:11.941 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:12.170 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:12.170 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed T2G92E0G0E5 2021-04-07 14:17:12.170 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> T2G92E0G0E5 2021-04-07 14:17:12.402 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:12.402 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed T2G92E0G0E5 2021-04-07 14:17:12.402 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:12.634 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:12.634 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:12.634 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:12.865 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:12.866 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:12.866 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:13.097 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:13.097 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:13.098 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:13.329 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:13.329 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:13.330 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:13.561 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:13.561 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:13.561 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:13.793 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:13.793 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:13.793 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X28.376 F2000 ; Send standard Gcode move 2021-04-07 14:17:14.025 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:14.025 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X28.376 F2000 ; Send standard Gcode move 2021-04-07 14:17:14.025 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:14.257 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:14.257 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:14.257 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:14.257 ReferenceActuator DEBUG: DRAGPIN.actuate(false) 2021-04-07 14:17:14.257 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M817 ; M816/817, 10500)... 2021-04-07 14:17:14.257 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G4P50 ; 100ms delay to deploy/retract, 10500)... 2021-04-07 14:17:14.257 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M817 ; M816/817 2021-04-07 14:17:14.488 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:14.489 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M817 ; M816/817 2021-04-07 14:17:14.489 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G4P50 ; 100ms delay to deploy/retract 2021-04-07 14:17:14.720 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:14.721 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G4P50 ; 100ms delay to deploy/retract 2021-04-07 14:17:14.721 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:14.721 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:17:14.721 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:17:14.721 ReferenceNozzle TRACE: N1.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:14.721 AbstractHeadMountable DEBUG: N1.moveTo((63.376000, -0.828000, 1.000000, 0.000000 mm), 1.0) 2021-04-07 14:17:14.721 ReferenceNozzle TRACE: N1.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:14.727 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:17:14.727 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:17:14.952 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:17:14.952 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:14.952 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:17:14.953 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:17:14.953 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:17:14.953 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 Z0.000 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:14.954 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:15.184 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:15.184 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:15.184 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:15.416 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:15.416 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:15.416 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:15.648 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:15.648 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:15.648 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:15.880 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:15.880 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:15.880 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:16.111 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:16.112 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:16.112 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:16.343 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:16.343 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:16.344 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 Z0.000 F8000 ; Send standard Gcode move 2021-04-07 14:17:16.575 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:16.575 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 Z0.000 F8000 ; Send standard Gcode move 2021-04-07 14:17:16.575 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:16.807 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:16.807 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:16.807 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:16.807 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:17:16.807 ReferenceNozzle TRACE: N2.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:16.808 ReferenceNozzle TRACE: N2.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:16.808 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:17:16.808 ReferenceDragFeeder DEBUG: getVisionOffsets(H1, (63.036000, 33.802000, -47.000000, 0.000000 mm)) 2021-04-07 14:17:16.808 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:17:16.808 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:17:16.808 ReferenceNozzle TRACE: N1.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:16.808 ReferenceNozzle TRACE: N1.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:16.808 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:17:16.808 ReferenceNozzle TRACE: N2.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:16.808 ReferenceNozzle TRACE: N2.transformToHeadLocation((28.376000, 17.572000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:16.808 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:17:16.808 ReferenceDragFeeder DEBUG: Move camera to pick location. 2021-04-07 14:17:16.808 AbstractHeadMountable DEBUG: Top Camera.moveTo((63.036000, 33.802000, -47.000000, 0.000000 mm), 1.0) 2021-04-07 14:17:16.808 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:17:16.808 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:17:17.039 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:17:17.039 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:17.039 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:17:17.039 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:17:17.039 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:17:17.039 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X63.036 Y33.802 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:17.040 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:17.271 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:17.271 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:17.271 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:17.502 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:17.503 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:17.503 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:17.734 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:17.734 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:17.735 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:17.966 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:17.966 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:17.966 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:18.198 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:18.198 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:18.198 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:18.430 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:18.430 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:18.430 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X63.036 Y33.802 F8000 ; Send standard Gcode move 2021-04-07 14:17:18.662 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:18.662 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X63.036 Y33.802 F8000 ; Send standard Gcode move 2021-04-07 14:17:18.662 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:18.894 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:18.894 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:18.894 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:18.894 ReferenceDragFeeder DEBUG: Perform template match. 2021-04-07 14:17:18.894 Scripting TRACE: Scripting.on Camera.BeforeSettle 2021-04-07 14:17:19.145 Scripting TRACE: Scripting.on Camera.BeforeCapture 2021-04-07 14:17:19.147 Scripting TRACE: Scripting.on Camera.AfterCapture 2021-04-07 14:17:19.147 Scripting TRACE: Scripting.on Camera.AfterSettle 2021-04-07 14:17:19.150 OpenCvVisionProvider DEBUG: locateTemplateMatches certainty 14127971.000000 at 142.000000, 59.000000 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: matchX 393.0, matchY 220.0 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: centered matchX 408.0, matchY 240.5 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: offsetX -48.0, offsetY -0.5 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: negated offsetX -48.0, offsetY 0.5 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: final, in camera units offsetX -0.0, offsetY 0.0 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: final visionOffsets (-0.000000, 0.000000, 0.000000, 0.000000 mm) 2021-04-07 14:17:19.156 ReferenceDragFeeder DEBUG: Modified pickLocation (63.036000, 33.802000, -47.000000, 0.000000 mm) 2021-04-07 14:17:19.156 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2021-04-07 14:17:19.157 AbstractHeadMountable DEBUG: N1.moveToSafeZ(1.0) 2021-04-07 14:17:19.157 ReferenceNozzle TRACE: N1.transformToHeadLocation((63.036000, 33.802000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:19.157 ReferenceNozzle TRACE: N1.transformToHeadLocation((63.036000, 33.802000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:19.157 AbstractHeadMountable DEBUG: N2.moveToSafeZ(1.0) 2021-04-07 14:17:19.157 ReferenceNozzle TRACE: N2.transformToHeadLocation((63.036000, 33.802000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:19.157 ReferenceNozzle TRACE: N2.transformToHeadLocation((63.036000, 33.802000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:19.157 AbstractHeadMountable DEBUG: DRAGPIN.moveToSafeZ(1.0) 2021-04-07 14:17:19.157 AbstractHeadMountable DEBUG: N1.moveTo((61.036000, 33.802000, 1.000000, 0.000000 mm), 1.0) 2021-04-07 14:17:19.157 ReferenceNozzle TRACE: N1.transformToHeadLocation((26.036000, 52.202000, 11.000000, 0.000000 mm), ...) 2021-04-07 14:17:19.157 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:17:19.157 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:17:19.357 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:17:19.357 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:19.357 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:17:19.358 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:17:19.358 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:17:19.358 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 X26.036 Y52.202 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:19.359 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:19.359 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:19.589 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:19.589 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:19.589 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:19.821 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:19.821 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:19.821 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:20.053 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:20.053 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:20.053 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:20.285 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:20.285 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:20.285 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:20.517 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:20.517 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:20.517 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:20.749 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:20.749 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:20.749 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 X26.036 Y52.202 F8000 ; Send standard Gcode move 2021-04-07 14:17:20.980 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:20.981 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 X26.036 Y52.202 F8000 ; Send standard Gcode move 2021-04-07 14:17:20.981 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:21.212 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:21.212 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:21.212 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:21.213 AbstractHeadMountable DEBUG: N1.moveTo((61.036000, 33.802000, -47.000000, 0.000000 mm), 1.0) 2021-04-07 14:17:21.213 ReferenceNozzle TRACE: N1.transformToHeadLocation((26.036000, 52.202000, -37.000000, 0.000000 mm), ...) 2021-04-07 14:17:21.213 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M1000 switch dragpinstate, 10500)... 2021-04-07 14:17:21.213 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M1000 switch dragpinstate 2021-04-07 14:17:21.444 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << switch dragpinstate is 0 2021-04-07 14:17:21.444 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:21.444 GcodeDriver TRACE: actuatorRead response: switch dragpinstate is 0 2021-04-07 14:17:21.444 ReferenceActuator DEBUG: DRAGPIN.read(): 0 2021-04-07 14:17:21.445 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M1000 switch dragpinstate 2021-04-07 14:17:21.445 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; X = X Axis, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Y = Y Axis, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; Z = Z Axis, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; A = right nozzle rotate, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; B = left nozzle rotate, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(; E = tape tension, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; X = X Axis 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(G0 Z-90.000 F8000 ; Send standard Gcode move, 10500)... 2021-04-07 14:17:21.446 GcodeAsyncDriver DEBUG: serial://tty.usbserial-FTBUBQMH commandQueue.offer(M400 ; Wait for moves to complete before returning, 10500)... 2021-04-07 14:17:21.676 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:21.676 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; X = X Axis 2021-04-07 14:17:21.676 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Y = Y Axis 2021-04-07 14:17:21.908 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:21.908 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Y = Y Axis 2021-04-07 14:17:21.908 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; Z = Z Axis 2021-04-07 14:17:22.140 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:22.140 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; Z = Z Axis 2021-04-07 14:17:22.140 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; A = right nozzle rotate 2021-04-07 14:17:22.371 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:22.372 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; A = right nozzle rotate 2021-04-07 14:17:22.372 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; B = left nozzle rotate 2021-04-07 14:17:22.603 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:22.603 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; B = left nozzle rotate 2021-04-07 14:17:22.603 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> ; E = tape tension 2021-04-07 14:17:22.835 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:22.835 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed ; E = tape tension 2021-04-07 14:17:22.835 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> G0 Z-90.000 F8000 ; Send standard Gcode move 2021-04-07 14:17:23.067 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << Error: Soft Endstop Z was exceeded - entire move ignored 2021-04-07 14:17:23.067 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:23.067 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed G0 Z-90.000 F8000 ; Send standard Gcode move 2021-04-07 14:17:23.067 GcodeAsyncDriver$WriterThread TRACE: [serial://tty.usbserial-FTBUBQMH] >> M400 ; Wait for moves to complete before returning 2021-04-07 14:17:23.299 GcodeDriver$ReaderThread TRACE: [serial://tty.usbserial-FTBUBQMH] << ok 2021-04-07 14:17:23.299 GcodeDriver TRACE: [serial://tty.usbserial-FTBUBQMH] confirmed M400 ; Wait for moves to complete before returning 2021-04-07 14:17:23.299 GcodeAsyncDriver TRACE: GcodeDriver confirmation complete. 2021-04-07 14:17:23.299 ReferenceNozzle DEBUG: N1.pick() 2021-04-07 14:17:23.299 Scripting TRACE: Scripting.on Nozzle.BeforePick 2021-04-07 14:17:23.304 MessageBoxes DEBUG: Error: java.lang.NullPointerException