2022-01-19 22:49:38.640 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2022-01-16_21-03-29.4082c2a. 2022-01-19 22:49:38.656 Scripting TRACE: Scripting.on Startup 2022-01-19 22:49:38.921 AbstractBroadcastingCamera TRACE: Camera Top Camera Down Facing thread 27 started. 2022-01-19 22:49:39.056 AbstractBroadcastingCamera TRACE: Camera Bottom Camera Facing up thread 29 started. 2022-01-19 22:49:42.933 ReferenceMachine DEBUG: setEnabled(true) 2022-01-19 22:49:43.680 SystemLogger ERROR: Exception in thread "AWT-EventQueue-0" java.lang.ArrayIndexOutOfBoundsException: 7 >= 7 2022-01-19 22:49:43.681 SystemLogger ERROR: at java.util.Vector.elementAt(Unknown Source) 2022-01-19 22:49:43.681 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI.getTextViewForTab(Unknown Source) 2022-01-19 22:49:43.682 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI.calculateTabHeight(Unknown Source) 2022-01-19 22:49:43.682 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI.calculateMaxTabHeight(Unknown Source) 2022-01-19 22:49:43.683 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI$TabbedPaneLayout.calculateTabRects(Unknown Source) 2022-01-19 22:49:43.684 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI$TabbedPaneLayout.calculateLayoutInfo(Unknown Source) 2022-01-19 22:49:43.686 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI$TabbedPaneLayout.layoutContainer(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at java.awt.Container.layout(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at java.awt.Container.doLayout(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at java.awt.Container.validateTree(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at java.awt.Container.validate(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI.ensureCurrentLayout(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at javax.swing.plaf.basic.BasicTabbedPaneUI.paint(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at javax.swing.plaf.ComponentUI.update(Unknown Source) 2022-01-19 22:49:43.690 SystemLogger ERROR: at javax.swing.JComponent.paintComponent(Unknown Source) 2022-01-19 22:49:43.692 SystemLogger ERROR: at javax.swing.JComponent.paint(Unknown Source) 2022-01-19 22:49:43.703 SystemLogger ERROR: at javax.swing.JComponent.paintChildren(Unknown Source) 2022-01-19 22:49:43.704 SystemLogger ERROR: at javax.swing.JSplitPane.paintChildren(Unknown Source) 2022-01-19 22:49:43.705 SystemLogger ERROR: at javax.swing.JComponent.paint(Unknown Source) 2022-01-19 22:49:43.705 SystemLogger ERROR: at javax.swing.JComponent.paintToOffscreen(Unknown Source) 2022-01-19 22:49:43.705 SystemLogger ERROR: at javax.swing.RepaintManager$PaintManager.paintDoubleBuffered(Unknown Source) 2022-01-19 22:49:43.705 SystemLogger ERROR: at javax.swing.RepaintManager$PaintManager.paint(Unknown Source) 2022-01-19 22:49:43.705 SystemLogger ERROR: at javax.swing.RepaintManager.paint(Unknown Source) 2022-01-19 22:49:43.706 SystemLogger ERROR: at javax.swing.JComponent._paintImmediately(Unknown Source) 2022-01-19 22:49:43.706 SystemLogger ERROR: at javax.swing.JComponent.paintImmediately(Unknown Source) 2022-01-19 22:49:43.706 SystemLogger ERROR: at javax.swing.RepaintManager$4.run(Unknown Source) 2022-01-19 22:49:43.706 SystemLogger ERROR: at javax.swing.RepaintManager$4.run(Unknown Source) 2022-01-19 22:49:43.707 SystemLogger ERROR: at java.security.AccessController.doPrivileged(Native Method) 2022-01-19 22:49:43.707 SystemLogger ERROR: at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(Unknown Source) 2022-01-19 22:49:43.707 SystemLogger ERROR: at javax.swing.RepaintManager.paintDirtyRegions(Unknown Source) 2022-01-19 22:49:43.707 SystemLogger ERROR: at javax.swing.RepaintManager.paintDirtyRegions(Unknown Source) 2022-01-19 22:49:43.710 SystemLogger ERROR: at javax.swing.RepaintManager.prePaintDirtyRegions(Unknown Source) 2022-01-19 22:49:43.716 SystemLogger ERROR: at javax.swing.RepaintManager.access$1200(Unknown Source) 2022-01-19 22:49:43.716 SystemLogger ERROR: at javax.swing.RepaintManager$ProcessingRunnable.run(Unknown Source) 2022-01-19 22:49:43.717 SystemLogger ERROR: at java.awt.event.InvocationEvent.dispatch(Unknown Source) 2022-01-19 22:49:43.717 SystemLogger ERROR: at java.awt.EventQueue.dispatchEventImpl(Unknown Source) 2022-01-19 22:49:43.717 SystemLogger ERROR: at java.awt.EventQueue.access$500(Unknown Source) 2022-01-19 22:49:43.718 SystemLogger ERROR: at java.awt.EventQueue$3.run(Unknown Source) 2022-01-19 22:49:43.719 SystemLogger ERROR: at java.awt.EventQueue$3.run(Unknown Source) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.security.AccessController.doPrivileged(Native Method) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.security.ProtectionDomain$JavaSecurityAccessImpl.doIntersectionPrivilege(Unknown Source) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.awt.EventQueue.dispatchEvent(Unknown Source) 2022-01-19 22:49:43.720 SystemLogger ERROR: at org.openpnp.gui.MainFrame$2.dispatchEvent(MainFrame.java:538) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpOneEventForFilters(Unknown Source) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEventsForFilter(Unknown Source) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEventsForHierarchy(Unknown Source) 2022-01-19 22:49:43.720 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEvents(Unknown Source) 2022-01-19 22:49:43.721 SystemLogger ERROR: at java.awt.EventDispatchThread.pumpEvents(Unknown Source) 2022-01-19 22:49:43.721 SystemLogger ERROR: at java.awt.EventDispatchThread.run(Unknown Source) 2022-01-19 22:49:49.071 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(G21 ; Set millimeters mode, 7000)... 2022-01-19 22:49:49.074 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(G90 ; Set absolute positioning mode, 7000)... 2022-01-19 22:49:49.094 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:49:49.097 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> G21 ; Set millimeters mode 2022-01-19 22:49:49.100 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:49:49.112 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> G90 ; Set absolute positioning mode 2022-01-19 22:49:56.430 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:49:56.431 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:49:56.432 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:49:56.440 Scripting TRACE: Scripting.on Job.Starting 2022-01-19 22:49:56.454 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:49:56.455 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:49:56.455 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:49:56.456 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:49:56.456 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:49:56.456 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:49:56.457 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:49:56.458 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:49:56.458 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:49:56.459 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((27.774000, -205.033000, NaN, NaN mm), 1.0) 2022-01-19 22:49:56.460 AbstractMachine TRACE: Exception caught, executing pending motion: org.openpnp.spi.JobProcessor$JobProcessorException: Can't move y to -205.033mm, lower than soft limit -34.986mm. at org.openpnp.machine.reference.ReferencePnpJobProcessor$Cleanup.step(ReferencePnpJobProcessor.java:1054) at org.openpnp.machine.reference.ReferencePnpJobProcessor$Finish.step(ReferencePnpJobProcessor.java:1063) at org.openpnp.machine.reference.ReferencePnpJobProcessor.next(ReferencePnpJobProcessor.java:114) at org.openpnp.gui.JobPanel.lambda$jobRun$2(JobPanel.java:903) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) Caused by: java.lang.Exception: Can't move y to -205.033mm, lower than soft limit -34.986mm. at org.openpnp.machine.reference.driver.AbstractMotionPlanner.limitAxesLocation(AbstractMotionPlanner.java:537) at org.openpnp.machine.reference.driver.AbstractMotionPlanner.moveTo(AbstractMotionPlanner.java:141) at org.openpnp.machine.reference.driver.NullMotionPlanner.moveTo(NullMotionPlanner.java:48) at org.openpnp.machine.reference.ReferenceHead.moveTo(ReferenceHead.java:150) at org.openpnp.spi.base.AbstractHeadMountable.moveTo(AbstractHeadMountable.java:242) at org.openpnp.spi.base.AbstractHeadMountable.moveTo(AbstractHeadMountable.java:269) at org.openpnp.util.MovableUtils.park(MovableUtils.java:63) at org.openpnp.machine.reference.ReferencePnpJobProcessor$Cleanup.step(ReferencePnpJobProcessor.java:1051) at org.openpnp.machine.reference.ReferencePnpJobProcessor$Finish.step(ReferencePnpJobProcessor.java:1063) at org.openpnp.machine.reference.ReferencePnpJobProcessor.next(ReferencePnpJobProcessor.java:114) at org.openpnp.gui.JobPanel.lambda$jobRun$2(JobPanel.java:903) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2022-01-19 22:49:56.522 MessageBoxes DEBUG: Job Error: Can't move y to -205.033mm, lower than soft limit -34.986mm. 2022-01-19 22:50:01.059 ReferenceMachine DEBUG: homing machine 2022-01-19 22:50:01.066 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S200.00 ; Initialize acceleration, -1)... 2022-01-19 22:50:01.070 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(G28 ; Home all axes, -1)... 2022-01-19 22:50:01.072 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, -1)... 2022-01-19 22:50:01.073 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:01.074 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S200.00 ; Initialize acceleration 2022-01-19 22:50:01.075 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:01.093 GcodeDriver$ReaderThread TRACE: [serial://COM9] << Error: Homing file homeall.g not found 2022-01-19 22:50:01.094 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:01.096 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> G28 ; Home all axes 2022-01-19 22:50:01.101 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:01.102 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:01.103 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:01.105 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:7.674 Y:148.092 Z:-7.000 C:0.000 E:0.000 Count 614 11847 -560 0 Machine 7.674 148.092 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:01.106 GcodeDriver TRACE: Position report: X:7.674 Y:148.092 Z:-7.000 C:0.000 E:0.000 Count 614 11847 -560 0 Machine 7.674 148.092 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:01.106 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:01.107 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:7.674000, y:148.092000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:01.108 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:01.109 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:0.000000, y:0.000000, C:0.000000, Z:0.000000) to (x:7.674000, y:148.092000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:01.113 ReferenceHead DEBUG: H1.home() 2022-01-19 22:50:01.114 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (-73.429000, -1.081000, -173.700000, 0.000000 mm) 2022-01-19 22:50:01.115 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:50:01.115 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:50:01.116 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:50:01.116 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.429000, -1.081000, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:01.148 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S227.65 G1 X-73.4290 Y-1.0810 F6829.45 ; move to target, 7000)... 2022-01-19 22:50:01.148 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:01.149 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:01.151 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S227.65 G1 X-73.4290 Y-1.0810 F6829.45 ; move to target 2022-01-19 22:50:01.153 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:01.154 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:01.155 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:03.213 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:03.215 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-73.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5874 -86 -560 0 Machine -73.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:03.215 GcodeDriver TRACE: Position report: X:-73.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5874 -86 -560 0 Machine -73.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:03.216 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:03.220 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-73.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:03.221 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:03.221 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.429000, -1.081000, -173.700000, 0.000000 mm), 1.0) 2022-01-19 22:50:03.229 ReferenceActuator DEBUG: Top Camera LED.actuate(true) 2022-01-19 22:50:03.230 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M4 M42 P3 S1, 7000)... 2022-01-19 22:50:03.231 Scripting TRACE: Scripting.on Camera.BeforeSettle 2022-01-19 22:50:03.233 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:03.267 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M4 M42 P3 S1 2022-01-19 22:50:03.282 GcodeDriver$ReaderThread TRACE: [serial://COM9] << Error: M4: Command is not supported in machine mode FFF 2022-01-19 22:50:03.294 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:03.304 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:03.339 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:03.424 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:03.445 AbstractCamera TRACE: autoSettleAndCapture t=214 auto settle score: 91.37254901960785 2022-01-19 22:50:03.446 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:03.503 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:03.518 AbstractCamera TRACE: autoSettleAndCapture t=287 auto settle score: 93.72549019607843 2022-01-19 22:50:03.518 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:03.596 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:03.611 AbstractCamera TRACE: autoSettleAndCapture t=380 auto settle score: 29.80392156862745 2022-01-19 22:50:03.611 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:03.760 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:03.778 AbstractCamera TRACE: autoSettleAndCapture t=547 auto settle score: 18.03921568627451 2022-01-19 22:50:03.778 AbstractCamera DEBUG: autoSettleAndCapture in 547 ms 2022-01-19 22:50:03.779 Scripting TRACE: Scripting.on Camera.AfterSettle 2022-01-19 22:50:03.820 CvPipeline DEBUG: Stage "circular" throws java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:03.824 CvPipeline DEBUG: Stage "results" throws java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:03.839 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. at org.openpnp.vision.pipeline.stages.DetectCircularSymmetry.findCircularSymmetry(DetectCircularSymmetry.java:388) at org.openpnp.vision.pipeline.stages.DetectCircularSymmetry.process(DetectCircularSymmetry.java:273) at org.openpnp.vision.pipeline.CvPipeline.process(CvPipeline.java:251) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:428) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:327) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:76) at org.openpnp.machine.reference.ReferenceHead.home(ReferenceHead.java:55) at org.openpnp.spi.base.AbstractMachine.home(AbstractMachine.java:301) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:447) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:333) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2022-01-19 22:50:03.859 MessageBoxes DEBUG: Error: java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:08.211 ReferenceMachine DEBUG: homing machine 2022-01-19 22:50:08.212 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S200.00 ; Initialize acceleration, -1)... 2022-01-19 22:50:08.212 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(G28 ; Home all axes, -1)... 2022-01-19 22:50:08.213 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, -1)... 2022-01-19 22:50:08.213 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:08.216 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S200.00 ; Initialize acceleration 2022-01-19 22:50:08.228 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:08.230 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> G28 ; Home all axes 2022-01-19 22:50:08.232 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:08.235 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:08.240 GcodeDriver$ReaderThread TRACE: [serial://COM9] << Error: Homing file homeall.g not found 2022-01-19 22:50:08.240 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:08.242 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:08.244 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-73.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5874 -86 -560 0 Machine -73.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:08.244 GcodeDriver TRACE: Position report: X:-73.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5874 -86 -560 0 Machine -73.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:08.251 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:08.251 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-73.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:08.251 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:08.252 ReferenceHead DEBUG: H1.home() 2022-01-19 22:50:08.252 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (-73.429000, -1.081000, -173.700000, 0.000000 mm) 2022-01-19 22:50:08.252 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.429000, -1.081000, -173.700000, 0.000000 mm), 1.0) 2022-01-19 22:50:08.253 Scripting TRACE: Scripting.on Camera.BeforeSettle 2022-01-19 22:50:08.254 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:08.308 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:08.322 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:08.451 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:08.465 AbstractCamera TRACE: autoSettleAndCapture t=211 auto settle score: 13.333333333333334 2022-01-19 22:50:08.465 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:08.564 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:08.579 AbstractCamera TRACE: autoSettleAndCapture t=324 auto settle score: 13.333333333333334 2022-01-19 22:50:08.579 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:08.709 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:08.724 AbstractCamera TRACE: autoSettleAndCapture t=470 auto settle score: 15.686274509803921 2022-01-19 22:50:08.725 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:08.852 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:08.867 AbstractCamera TRACE: autoSettleAndCapture t=613 auto settle score: 12.941176470588237 2022-01-19 22:50:08.867 AbstractCamera DEBUG: autoSettleAndCapture in 613 ms 2022-01-19 22:50:08.867 Scripting TRACE: Scripting.on Camera.AfterSettle 2022-01-19 22:50:08.892 CvPipeline DEBUG: Stage "circular" throws java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:08.900 CvPipeline DEBUG: Stage "results" throws java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:08.917 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. at org.openpnp.vision.pipeline.stages.DetectCircularSymmetry.findCircularSymmetry(DetectCircularSymmetry.java:388) at org.openpnp.vision.pipeline.stages.DetectCircularSymmetry.process(DetectCircularSymmetry.java:273) at org.openpnp.vision.pipeline.CvPipeline.process(CvPipeline.java:251) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:428) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:327) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:76) at org.openpnp.machine.reference.ReferenceHead.home(ReferenceHead.java:55) at org.openpnp.spi.base.AbstractMachine.home(AbstractMachine.java:301) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:447) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:333) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2022-01-19 22:50:08.918 MessageBoxes DEBUG: Error: java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:12.443 ReferenceMachine DEBUG: homing machine 2022-01-19 22:50:12.444 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S200.00 ; Initialize acceleration, -1)... 2022-01-19 22:50:12.445 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(G28 ; Home all axes, -1)... 2022-01-19 22:50:12.445 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, -1)... 2022-01-19 22:50:12.445 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:12.446 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:12.446 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S200.00 ; Initialize acceleration 2022-01-19 22:50:12.448 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> G28 ; Home all axes 2022-01-19 22:50:12.450 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:12.452 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:12.458 GcodeDriver$ReaderThread TRACE: [serial://COM9] << Error: Homing file homeall.g not found 2022-01-19 22:50:12.459 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:12.459 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:12.461 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-73.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5874 -86 -560 0 Machine -73.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:12.462 GcodeDriver TRACE: Position report: X:-73.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5874 -86 -560 0 Machine -73.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:12.462 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:12.462 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-73.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:12.462 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:12.463 ReferenceHead DEBUG: H1.home() 2022-01-19 22:50:12.463 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (-73.429000, -1.081000, -173.700000, 0.000000 mm) 2022-01-19 22:50:12.464 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.429000, -1.081000, -173.700000, 0.000000 mm), 1.0) 2022-01-19 22:50:12.466 Scripting TRACE: Scripting.on Camera.BeforeSettle 2022-01-19 22:50:12.466 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:12.526 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:12.538 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:12.621 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:12.636 AbstractCamera TRACE: autoSettleAndCapture t=170 auto settle score: 14.50980392156863 2022-01-19 22:50:12.636 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:12.728 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:12.742 AbstractCamera TRACE: autoSettleAndCapture t=276 auto settle score: 14.117647058823529 2022-01-19 22:50:12.743 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:12.852 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:12.867 AbstractCamera TRACE: autoSettleAndCapture t=401 auto settle score: 12.156862745098039 2022-01-19 22:50:12.867 Scripting TRACE: Scripting.on Camera.BeforeCapture 2022-01-19 22:50:12.990 Scripting TRACE: Scripting.on Camera.AfterCapture 2022-01-19 22:50:13.004 AbstractCamera TRACE: autoSettleAndCapture t=538 auto settle score: 13.725490196078432 2022-01-19 22:50:13.005 AbstractCamera DEBUG: autoSettleAndCapture in 539 ms 2022-01-19 22:50:13.006 Scripting TRACE: Scripting.on Camera.AfterSettle 2022-01-19 22:50:13.022 CvPipeline DEBUG: Stage "circular" throws java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:13.027 CvPipeline DEBUG: Stage "results" throws java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:13.042 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. at org.openpnp.vision.pipeline.stages.DetectCircularSymmetry.findCircularSymmetry(DetectCircularSymmetry.java:388) at org.openpnp.vision.pipeline.stages.DetectCircularSymmetry.process(DetectCircularSymmetry.java:273) at org.openpnp.vision.pipeline.CvPipeline.process(CvPipeline.java:251) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:428) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:327) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:76) at org.openpnp.machine.reference.ReferenceHead.home(ReferenceHead.java:55) at org.openpnp.spi.base.AbstractMachine.home(AbstractMachine.java:301) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:447) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:333) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:38) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:571) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 2022-01-19 22:50:13.044 MessageBoxes DEBUG: Error: java.lang.Exception: Circular symmetry stage: search range is cropped to nothing. 2022-01-19 22:50:19.196 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:50:19.196 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:50:19.196 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:50:19.196 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.429000, -1.081000, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:19.197 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.107598, -0.972614, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:19.204 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S528.37 G1 X-73.1076 Y-0.9726 F803.23 ; move to target, 7000)... 2022-01-19 22:50:19.204 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:19.204 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:19.208 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S528.37 G1 X-73.1076 Y-0.9726 F803.23 ; move to target 2022-01-19 22:50:19.210 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:19.212 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:19.233 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:19.329 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:19.331 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-73.108 Y:-0.973 Z:-7.000 C:0.000 E:0.000 Count -5849 -78 -560 0 Machine -73.108 -0.973 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:19.332 GcodeDriver TRACE: Position report: X:-73.108 Y:-0.973 Z:-7.000 C:0.000 E:0.000 Count -5849 -78 -560 0 Machine -73.108 -0.973 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:19.332 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:19.332 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-73.108000, y:-0.973000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:19.333 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:19.333 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:-73.107598, y:-0.972614, C:0.000000, Z:-7.000000) to (x:-73.108000, y:-0.973000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:19.333 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.107598, -0.972614, -173.700000, 0.000000 mm), 1.0) 2022-01-19 22:50:19.340 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S288.97 G1 X-73.1076 Y-0.9726 F24.08 ; move to target, 7000)... 2022-01-19 22:50:19.342 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:19.342 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:19.346 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:19.346 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S288.97 G1 X-73.1076 Y-0.9726 F24.08 ; move to target 2022-01-19 22:50:19.349 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:19.349 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:19.351 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:19.353 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-73.108 Y:-0.973 Z:-7.000 C:0.000 E:0.000 Count -5849 -78 -560 0 Machine -73.108 -0.973 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:19.354 GcodeDriver TRACE: Position report: X:-73.108 Y:-0.973 Z:-7.000 C:0.000 E:0.000 Count -5849 -78 -560 0 Machine -73.108 -0.973 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:19.356 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:19.358 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-73.108000, y:-0.973000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:19.359 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:19.359 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:-73.107598, y:-0.972614, C:0.000000, Z:-7.000000) to (x:-73.108000, y:-0.973000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:21.754 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:50:21.754 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:50:21.754 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:50:21.755 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-73.108000, -0.973000, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:21.756 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-72.429484, -1.081386, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:21.763 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S507.01 G1 X-72.4295 Y-1.0814 F1119.89 ; move to target, 7000)... 2022-01-19 22:50:21.763 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:21.763 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:21.765 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:21.765 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S507.01 G1 X-72.4295 Y-1.0814 F1119.89 ; move to target 2022-01-19 22:50:21.779 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:21.781 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:21.909 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:21.911 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-72.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5794 -87 -560 0 Machine -72.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:21.911 GcodeDriver TRACE: Position report: X:-72.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5794 -87 -560 0 Machine -72.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:21.911 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:21.911 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-72.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:21.912 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:21.912 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:-72.429484, y:-1.081386, C:0.000000, Z:-7.000000) to (x:-72.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:21.913 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-72.429484, -1.081386, -173.700000, 0.000000 mm), 1.0) 2022-01-19 22:50:21.924 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S320.66 G1 X-72.4295 Y-1.0814 F26.73 ; move to target, 7000)... 2022-01-19 22:50:21.924 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:21.925 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:21.927 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S320.66 G1 X-72.4295 Y-1.0814 F26.73 ; move to target 2022-01-19 22:50:21.927 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:21.929 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:21.929 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:21.931 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:21.933 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-72.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5794 -87 -560 0 Machine -72.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:21.933 GcodeDriver TRACE: Position report: X:-72.429 Y:-1.081 Z:-7.000 C:0.000 E:0.000 Count -5794 -87 -560 0 Machine -72.429 -1.081 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:21.933 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:21.933 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-72.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:21.933 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:21.934 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:-72.429484, y:-1.081386, C:0.000000, Z:-7.000000) to (x:-72.429000, y:-1.081000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:24.211 ReferenceHead DEBUG: H1.moveToSafeZ(1.0) 2022-01-19 22:50:24.212 AbstractHeadMountable DEBUG: N.moveToSafeZ(1.0) 2022-01-19 22:50:24.212 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveToSafeZ(1.0) 2022-01-19 22:50:24.212 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-72.429000, -1.081000, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:24.213 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-71.250524, -1.117129, 0.000000, 0.000000 mm), 1.0) 2022-01-19 22:50:24.216 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S500.90 G1 X-71.2505 Y-1.1171 F1458.11 ; move to target, 7000)... 2022-01-19 22:50:24.217 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:24.217 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:24.223 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S500.90 G1 X-71.2505 Y-1.1171 F1458.11 ; move to target 2022-01-19 22:50:24.223 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:24.224 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:24.226 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:24.386 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:24.388 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-71.251 Y:-1.117 Z:-7.000 C:0.000 E:0.000 Count -5700 -89 -560 0 Machine -71.251 -1.117 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:24.388 GcodeDriver TRACE: Position report: X:-71.251 Y:-1.117 Z:-7.000 C:0.000 E:0.000 Count -5700 -89 -560 0 Machine -71.251 -1.117 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:24.388 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-71.251000, y:-1.117000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:24.388 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:24.389 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:-71.250524, y:-1.117129, C:0.000000, Z:-7.000000) to (x:-71.251000, y:-1.117000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:24.389 AbstractHeadMountable DEBUG: Top Camera Down Facing.moveTo((-71.250524, -1.117129, -173.700000, 0.000000 mm), 1.0) 2022-01-19 22:50:24.392 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M204 S518.65 G1 X-71.2505 Y-1.1171 F30.34 ; move to target, 7000)... 2022-01-19 22:50:24.392 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M400 ; Wait for moves to complete before returning, 7000)... 2022-01-19 22:50:24.393 GcodeAsyncDriver DEBUG: serial://COM9 commandQueue.offer(M114 ; get position, -1)... 2022-01-19 22:50:24.393 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:24.398 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M204 S518.65 G1 X-71.2505 Y-1.1171 F30.34 ; move to target 2022-01-19 22:50:24.399 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:24.400 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M400 ; Wait for moves to complete before returning 2022-01-19 22:50:24.401 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:24.402 GcodeAsyncDriver$WriterThread TRACE: [serial://COM9] >> M114 ; get position 2022-01-19 22:50:24.405 GcodeDriver$ReaderThread TRACE: [serial://COM9] << X:-71.251 Y:-1.117 Z:-7.000 C:0.000 E:0.000 Count -5700 -89 -560 0 Machine -71.251 -1.117 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:24.405 GcodeDriver TRACE: Position report: X:-71.251 Y:-1.117 Z:-7.000 C:0.000 E:0.000 Count -5700 -89 -560 0 Machine -71.251 -1.117 -7.000 0.000 Bed comp 0.000 2022-01-19 22:50:24.406 GcodeDriver$ReaderThread TRACE: [serial://COM9] << ok 2022-01-19 22:50:24.408 GcodeDriver TRACE: GcodeAsyncDriver got lastReportedLocation (x:-71.251000, y:-1.117000, C:0.000000, Z:-7.000000) 2022-01-19 22:50:24.409 GcodeAsyncDriver TRACE: GcodeAsyncDriver confirmation complete. 2022-01-19 22:50:24.409 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:-71.250524, y:-1.117129, C:0.000000, Z:-7.000000) to (x:-71.251000, y:-1.117000, C:0.000000, Z:-7.000000)