2024-04-02 08:38:05.982 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2024-03-27_09-59-31.0b0aca0. 2024-04-02 08:38:05.984 Scripting TRACE: Scripting.on Startup 2024-04-02 08:38:07.506 AbstractBroadcastingCamera TRACE: Camera DOWN thread 49 started. 2024-04-02 08:38:08.264 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 51 started. 2024-04-02 08:38:11.077 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:38:11.079 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:38:11.094 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:38:11.092 GcodeDriver$ReaderThread ERROR: [Smoothie:COM4] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:38:26.104 MessageBoxes DEBUG: Error: java.lang.Exception: Smoothie:COM4 IO Error on reading from the controller. 2024-04-02 08:39:23.683 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:39:25.433 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:39:25.433 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:39:25.436 GcodeDriver$ReaderThread ERROR: [Smoothie:COM4] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:39:25.438 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:39:40.439 MessageBoxes DEBUG: Error: java.lang.Exception: Smoothie:COM4 IO Error on reading from the controller. 2024-04-02 08:39:42.286 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:39:49.129 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:39:49.129 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:39:49.134 GcodeDriver$ReaderThread ERROR: [Smoothie:COM4] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:39:49.139 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:40:04.141 MessageBoxes DEBUG: Error: java.lang.Exception: Smoothie:COM4 IO Error on reading from the controller. 2024-04-02 08:40:12.012 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:40:55.454 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:40:55.454 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:40:55.458 GcodeDriver$ReaderThread ERROR: [Smoothie:COM4] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:40:55.461 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:41:10.463 MessageBoxes DEBUG: Error: java.lang.Exception: Smoothie:COM4 IO Error on reading from the controller. 2024-04-02 08:41:32.172 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:41:35.893 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:41:35.894 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:41:35.897 GcodeDriver$ReaderThread ERROR: [Smoothie:COM4] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:41:35.900 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:41:50.902 MessageBoxes DEBUG: Error: java.lang.Exception: Smoothie:COM4 IO Error on reading from the controller. 2024-04-02 08:42:05.302 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:42:34.268 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:42:34.268 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:42:34.283 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:42:49.284 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-02 08:42:49.284 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 08:42:49.284 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-02 08:42:49.284 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:42:49.284 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-02 08:42:49.284 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-02 08:42:49.284 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:42:49.284 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-02 08:42:49.285 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-02 08:42:49.285 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:42:49.285 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:42:49.289 GcodeDriver$ReaderThread ERROR: [Schultz:COM3] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:42:49.292 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-02 08:42:52.294 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] Read error while disconnecting (expected) 2024-04-02 08:42:52.310 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:42:52.354 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:42:52.362 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:42:52.364 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:42:52.385 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:42:52.395 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:42:52.395 MessageBoxes DEBUG: Error: java.lang.Exception: Schultz:COM3 IO Error on reading from the controller. 2024-04-02 08:42:54.887 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:43:09.456 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:43:09.456 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:43:09.471 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:43:24.474 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-02 08:43:24.474 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 08:43:24.474 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-02 08:43:24.474 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-02 08:43:24.475 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:43:24.475 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-02 08:43:24.475 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:43:24.475 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:43:24.475 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-02 08:43:24.475 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-02 08:43:24.476 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:43:24.481 GcodeDriver$ReaderThread ERROR: [Schultz:COM3] Read error: java.io.IOException: Read error. at org.openpnp.machine.reference.driver.SerialPortCommunications.read(SerialPortCommunications.java:150) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readUntil(ReferenceDriverCommunications.java:108) at org.openpnp.machine.reference.driver.ReferenceDriverCommunications.readLine(ReferenceDriverCommunications.java:86) at org.openpnp.machine.reference.driver.GcodeDriver$ReaderThread.run(GcodeDriver.java:1487) 2024-04-02 08:43:24.485 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-02 08:43:27.486 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] Read error while disconnecting (expected) 2024-04-02 08:43:27.588 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 08:43:27.588 MessageBoxes DEBUG: Error: java.lang.Exception: Schultz:COM3 IO Error on reading from the controller. 2024-04-02 08:43:29.207 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:44:06.065 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 08:44:06.066 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 08:44:06.079 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 08:44:21.080 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-02 08:44:21.080 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 08:44:21.081 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-02 08:44:21.081 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-02 08:44:21.081 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:21.081 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:44:21.082 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-02 08:44:21.082 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:21.082 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-02 08:44:21.083 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-02 08:44:21.083 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:21.095 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-02 08:44:21.156 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller starting... 2024-04-02 08:44:21.159 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller ready. 2024-04-02 08:44:24.097 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G21 ; Set millimeters mode 2024-04-02 08:44:24.097 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 08:44:24.097 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:44:24.097 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G21 ; Set millimeters mode 2024-04-02 08:44:24.100 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G21 ; Set millimeters mode 2024-04-02 08:44:24.103 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:44:24.103 GcodeDriver TRACE: [Schultz:COM3] confirmed G21 ; Set millimeters mode 2024-04-02 08:44:24.103 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G90 ; Set absolute positioning mode 2024-04-02 08:44:24.107 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G90 ; Set absolute positioning mode 2024-04-02 08:44:24.110 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:44:24.113 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:44:26.901 ReferenceMachine DEBUG: homing machine 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:44:26.903 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 08:44:26.903 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-02 08:44:26.903 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:44:26.904 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:26.904 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:44:26.904 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:44:26.904 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 08:44:26.905 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:44:26.905 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-02 08:44:26.905 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:44:26.905 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 08:44:26.905 GcodeDriver TRACE: [Schultz:COM3] confirmed G90 ; Set absolute positioning mode 2024-04-02 08:44:26.905 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:44:26.905 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:44:26.905 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-02 08:44:26.906 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:44:26.905 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:44:26.906 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:44:26.907 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-02 08:44:26.910 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:44:27.980 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:27.981 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.880 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.880 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.881 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.881 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.881 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.882 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:44:31.882 GcodeDriver TRACE: Position report: ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:44:31.883 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:44:31.883 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:44:31.884 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:44:31.884 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-02 08:44:31.885 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:44:31.889 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-02 08:44:31.893 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:44:31.893 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-02 08:44:31.893 GcodeAsyncDriver TRACE: Schultz waited 9ms to drain command queue. 2024-04-02 08:44:31.893 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-02 08:44:31.894 ReferenceHead DEBUG: H1.home() 2024-04-02 08:44:31.894 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-02 08:44:31.895 ReferenceHead DEBUG: H1.moveToSafeZ(0.76) 2024-04-02 08:44:31.895 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.76) 2024-04-02 08:44:31.895 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.76) 2024-04-02 08:44:31.895 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.76) 2024-04-02 08:44:31.895 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.76) 2024-04-02 08:44:31.895 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.76) 2024-04-02 08:44:31.896 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.76) 2024-04-02 08:44:31.916 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:44:31.923 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1051 G1 X110.0300 Y43.1008 F9377 ; move to target 2024-04-02 08:44:31.924 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1051 G1 X110.0300 Y43.1008 F9377 ; move to target 2024-04-02 08:44:31.925 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X110.0000 Y43.5533 F277 ; move to target 2024-04-02 08:44:31.925 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:31.925 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X110.0000 Y43.5533 F277 ; move to target 2024-04-02 08:44:31.925 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:44:31.926 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:44:31.926 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:44:31.926 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:44:31.926 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:32.953 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:32.954 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:110.0000 Y:43.5533 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:44:32.954 GcodeDriver TRACE: Position report: ok C: X:110.0000 Y:43.5533 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:44:32.955 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:110.000000, y:43.553300, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:44:32.956 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:44:32.957 ReferenceActuator DEBUG: UpCamLight.actuate(false) 2024-04-02 08:44:32.957 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M811 ; turn off Light 2024-04-02 08:44:32.958 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 08:44:32.958 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M811 ; turn off Light 2024-04-02 08:44:32.958 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:44:32.960 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:44:33.462 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:33.963 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:34.464 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:34.965 ReferenceCamera WARNING: Camera DOWN failed to return an image after 4 tries. 2024-04-02 08:44:34.975 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:44:34.980 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:44:35.482 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:35.983 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:36.484 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:36.985 ReferenceCamera WARNING: Camera DOWN failed to return an image after 4 tries. 2024-04-02 08:44:36.994 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:44:37.005 AbstractSettlingCamera TRACE: autoSettleAndCapture t=4044 auto settle score: 0.000 compute time: 8 2024-04-02 08:44:37.005 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 4047 ms 2024-04-02 08:44:37.006 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 08:44:37.036 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)' > THRESH_OTSU mode: > 'src_type == CV_8UC1 || src_type == CV_16UC1' > where > 'src_type' is 16 (CV_8UC3) ] 2024-04-02 08:44:37.071 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: FIDUCIAL-HOME no matches found. at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.detectFiducialFromViewpoint(ReferenceFiducialLocator.java:528) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:463) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:344) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:77) at org.openpnp.machine.reference.ReferenceHead.home(ReferenceHead.java:56) at org.openpnp.spi.base.AbstractMachine.home(AbstractMachine.java:301) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:531) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:420) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:67) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:575) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2024-04-02 08:44:37.072 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:44:37.072 MessageBoxes DEBUG: Error: java.lang.Exception: FIDUCIAL-HOME no matches found. 2024-04-02 08:44:58.527 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:59.028 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:44:59.529 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:00.030 ReferenceCamera WARNING: Camera DOWN failed to return an image after 4 tries. 2024-04-02 08:45:00.558 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:01.059 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:01.560 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:02.061 ReferenceCamera WARNING: Camera DOWN failed to return an image after 4 tries. 2024-04-02 08:45:06.412 ReferenceMachine DEBUG: homing machine 2024-04-02 08:45:06.413 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-02 08:45:06.413 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-02 08:45:06.413 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:45:06.413 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 08:45:06.413 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:45:06.413 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 08:45:06.414 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:45:06.414 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-02 08:45:06.414 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:45:06.414 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-02 08:45:06.414 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-02 08:45:06.414 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:45:06.414 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:45:06.414 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:06.414 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 08:45:06.414 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-02 08:45:06.414 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:45:06.415 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:45:06.415 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:45:06.415 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 08:45:06.415 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:45:06.416 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:45:06.416 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:45:06.416 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-02 08:45:06.419 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:45:07.499 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:07.499 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.956 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.956 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.957 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.957 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.957 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.958 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:45:09.958 GcodeDriver TRACE: Position report: ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:45:09.959 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:45:09.959 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:45:09.959 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:45:09.960 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-02 08:45:09.961 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:45:09.965 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-02 08:45:09.968 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:45:09.969 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-02 08:45:09.969 GcodeAsyncDriver TRACE: Schultz waited 9ms to drain command queue. 2024-04-02 08:45:09.969 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-02 08:45:09.969 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:110.000000, y:43.500000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) to (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:45:09.970 ReferenceHead DEBUG: H1.home() 2024-04-02 08:45:09.970 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-02 08:45:09.970 ReferenceHead DEBUG: H1.moveToSafeZ(0.76) 2024-04-02 08:45:09.970 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.76) 2024-04-02 08:45:09.971 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.76) 2024-04-02 08:45:09.971 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.76) 2024-04-02 08:45:09.971 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.76) 2024-04-02 08:45:09.971 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.76) 2024-04-02 08:45:09.971 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.76) 2024-04-02 08:45:09.975 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:45:09.976 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 08:45:09.977 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1051 G1 X110.0300 Y43.1008 F9377 ; move to target 2024-04-02 08:45:09.978 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1051 G1 X110.0300 Y43.1008 F9377 ; move to target 2024-04-02 08:45:09.978 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X110.0000 Y43.5533 F277 ; move to target 2024-04-02 08:45:09.978 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:45:09.978 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:45:09.978 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X110.0000 Y43.5533 F277 ; move to target 2024-04-02 08:45:09.979 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:45:09.979 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:45:09.979 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:09.980 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:11.006 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:45:11.008 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:110.0000 Y:43.5533 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:45:11.008 GcodeDriver TRACE: Position report: ok C: X:110.0000 Y:43.5533 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:45:11.009 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:110.000000, y:43.553300, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:45:11.009 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:45:11.009 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:45:11.511 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:12.012 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:12.513 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:13.014 ReferenceCamera WARNING: Camera DOWN failed to return an image after 4 tries. 2024-04-02 08:45:13.016 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:45:13.018 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:45:13.520 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:14.021 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:14.522 ReferenceCamera TRACE: Camera DOWN failed to return an image. Retrying. 2024-04-02 08:45:15.023 ReferenceCamera WARNING: Camera DOWN failed to return an image after 4 tries. 2024-04-02 08:45:15.026 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:45:15.028 AbstractSettlingCamera TRACE: autoSettleAndCapture t=4019 auto settle score: 0.000 compute time: 1 2024-04-02 08:45:15.031 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 4022 ms 2024-04-02 08:45:15.031 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 08:45:15.047 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)' > THRESH_OTSU mode: > 'src_type == CV_8UC1 || src_type == CV_16UC1' > where > 'src_type' is 16 (CV_8UC3) ] 2024-04-02 08:45:15.058 AbstractMachine TRACE: Exception caught, executing pending motion: java.lang.Exception: FIDUCIAL-HOME no matches found. at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.detectFiducialFromViewpoint(ReferenceFiducialLocator.java:528) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getFiducialLocation(ReferenceFiducialLocator.java:463) at org.openpnp.machine.reference.vision.ReferenceFiducialLocator.getHomeFiducialLocation(ReferenceFiducialLocator.java:344) at org.openpnp.machine.reference.ReferenceHead.visualHome(ReferenceHead.java:77) at org.openpnp.machine.reference.ReferenceHead.home(ReferenceHead.java:56) at org.openpnp.spi.base.AbstractMachine.home(AbstractMachine.java:301) at org.openpnp.machine.reference.ReferenceMachine.home(ReferenceMachine.java:531) at org.openpnp.gui.MachineControlsPanel$HomeAction.lambda$actionPerformed$0(MachineControlsPanel.java:420) at org.openpnp.util.UiUtils.lambda$submitUiMachineTask$0(UiUtils.java:67) at org.openpnp.spi.base.AbstractMachine$1.call(AbstractMachine.java:575) at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.base/java.lang.Thread.run(Thread.java:833) 2024-04-02 08:45:15.058 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 08:45:15.058 MessageBoxes DEBUG: Error: java.lang.Exception: FIDUCIAL-HOME no matches found. 2024-04-02 08:45:31.189 AbstractBroadcastingCamera TRACE: Camera DOWN thread 49 bye-bye. 2024-04-02 08:45:32.913 AbstractBroadcastingCamera TRACE: Camera DOWN thread 77 started. 2024-04-02 08:45:36.125 AbstractBroadcastingCamera TRACE: Camera DOWN thread 77 bye-bye. 2024-04-02 08:45:36.125 AbstractBroadcastingCamera TRACE: Camera DOWN thread 78 started. 2024-04-02 08:46:01.817 AbstractBroadcastingCamera TRACE: Camera DOWN thread 78 bye-bye. 2024-04-02 08:46:04.717 AbstractBroadcastingCamera TRACE: Camera DOWN thread 79 started. 2024-04-02 08:46:10.697 AbstractBroadcastingCamera TRACE: Camera DOWN thread 79 bye-bye. 2024-04-02 08:46:12.539 AbstractBroadcastingCamera TRACE: Camera DOWN thread 80 started. 2024-04-02 08:46:12.555 ReferenceMachine DEBUG: homing machine 2024-04-02 08:46:12.555 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 08:46:12.556 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-02 08:46:12.556 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:46:12.556 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-02 08:46:12.556 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-02 08:46:12.556 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:12.556 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 08:46:12.556 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:46:12.557 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-02 08:46:12.557 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:12.557 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 08:46:12.557 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:46:12.557 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:46:12.557 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 08:46:12.557 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 08:46:12.558 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:12.558 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:46:12.559 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-02 08:46:12.562 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:46:13.640 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:13.641 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.099 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.099 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.099 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.099 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.100 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.100 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:16.100 GcodeDriver TRACE: Position report: ok C: X:0.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:16.101 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:46:16.101 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:46:16.101 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:16.101 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-02 08:46:16.101 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:16.106 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-02 08:46:16.109 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 08:46:16.110 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-02 08:46:16.110 GcodeAsyncDriver TRACE: Schultz waited 9ms to drain command queue. 2024-04-02 08:46:16.110 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-02 08:46:16.110 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:110.000000, y:43.500000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) to (x:0.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:46:16.110 ReferenceHead DEBUG: H1.home() 2024-04-02 08:46:16.110 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-02 08:46:16.110 ReferenceHead DEBUG: H1.moveToSafeZ(0.76) 2024-04-02 08:46:16.111 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.76) 2024-04-02 08:46:16.111 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.76) 2024-04-02 08:46:16.111 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.76) 2024-04-02 08:46:16.111 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.76) 2024-04-02 08:46:16.111 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.76) 2024-04-02 08:46:16.112 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:16.114 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:16.115 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 08:46:16.117 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1051 G1 X110.0300 Y43.1008 F9377 ; move to target 2024-04-02 08:46:16.117 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1051 G1 X110.0300 Y43.1008 F9377 ; move to target 2024-04-02 08:46:16.117 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X110.0000 Y43.5533 F277 ; move to target 2024-04-02 08:46:16.117 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:16.117 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X110.0000 Y43.5533 F277 ; move to target 2024-04-02 08:46:16.117 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:46:16.118 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:16.118 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:46:16.118 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:16.119 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:17.145 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:17.146 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:110.0000 Y:43.5533 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:17.146 GcodeDriver TRACE: Position report: ok C: X:110.0000 Y:43.5533 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:17.146 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:110.000000, y:43.553300, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:46:17.146 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:46:17.146 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.157 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.162 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.173 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.177 AbstractSettlingCamera TRACE: autoSettleAndCapture t=30 auto settle score: 81.176 compute time: 3 2024-04-02 08:46:17.178 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.203 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.209 AbstractSettlingCamera TRACE: autoSettleAndCapture t=62 auto settle score: 60.784 compute time: 5 2024-04-02 08:46:17.209 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.236 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.240 AbstractSettlingCamera TRACE: autoSettleAndCapture t=93 auto settle score: 23.137 compute time: 4 2024-04-02 08:46:17.240 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.267 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.272 AbstractSettlingCamera TRACE: autoSettleAndCapture t=125 auto settle score: 22.745 compute time: 4 2024-04-02 08:46:17.273 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 126 ms 2024-04-02 08:46:17.273 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 08:46:17.294 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)' > THRESH_OTSU mode: > 'src_type == CV_8UC1 || src_type == CV_16UC1' > where > 'src_type' is 16 (CV_8UC3) ] 2024-04-02 08:46:17.336 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (111.674877, 44.777749, -25.000000, 0.000000 mm) 2024-04-02 08:46:17.337 AbstractHeadMountable DEBUG: DOWN.moveTo((111.674877, 44.777749, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:17.344 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 08:46:17.345 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S370 G1 X111.7049 Y44.3785 F1123 ; move to target 2024-04-02 08:46:17.345 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S370 G1 X111.7049 Y44.3785 F1123 ; move to target 2024-04-02 08:46:17.346 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X111.6749 Y44.8311 F277 ; move to target 2024-04-02 08:46:17.346 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X111.6749 Y44.8311 F277 ; move to target 2024-04-02 08:46:17.346 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:17.346 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:46:17.346 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:17.346 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:17.346 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:46:17.347 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:17.613 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:17.614 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:111.6749 Y:44.8311 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:17.614 GcodeDriver TRACE: Position report: ok C: X:111.6749 Y:44.8311 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:17.614 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:111.674900, y:44.831100, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:46:17.615 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:46:17.615 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.628 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.633 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.645 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.650 AbstractSettlingCamera TRACE: autoSettleAndCapture t=35 auto settle score: 83.529 compute time: 5 2024-04-02 08:46:17.650 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.674 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.679 AbstractSettlingCamera TRACE: autoSettleAndCapture t=64 auto settle score: 67.843 compute time: 5 2024-04-02 08:46:17.679 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.703 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.708 AbstractSettlingCamera TRACE: autoSettleAndCapture t=93 auto settle score: 20.000 compute time: 4 2024-04-02 08:46:17.708 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:17.732 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:17.737 AbstractSettlingCamera TRACE: autoSettleAndCapture t=122 auto settle score: 16.078 compute time: 5 2024-04-02 08:46:17.737 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 122 ms 2024-04-02 08:46:17.737 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 08:46:17.759 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)' > THRESH_OTSU mode: > 'src_type == CV_8UC1 || src_type == CV_16UC1' > where > 'src_type' is 16 (CV_8UC3) ] 2024-04-02 08:46:17.798 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.016056, 45.097187, -25.000000, 0.000000 mm) 2024-04-02 08:46:17.798 AbstractHeadMountable DEBUG: DOWN.moveTo((112.016056, 45.097187, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:17.808 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 08:46:17.816 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S200 G1 X112.0461 F366 ; move to target 2024-04-02 08:46:17.816 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S200 G1 X112.0461 F366 ; move to target 2024-04-02 08:46:17.817 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X112.0161 Y45.1505 F233 ; move to target 2024-04-02 08:46:17.817 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:17.817 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X112.0161 Y45.1505 F233 ; move to target 2024-04-02 08:46:17.817 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:17.817 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:46:17.817 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:17.818 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:46:17.818 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.003 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.004 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:112.0161 Y:45.1505 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:18.004 GcodeDriver TRACE: Position report: ok C: X:112.0161 Y:45.1505 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 08:46:18.004 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.016100, y:45.150500, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 08:46:18.004 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 08:46:18.004 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:18.014 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:18.018 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:18.036 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:18.041 AbstractSettlingCamera TRACE: autoSettleAndCapture t=36 auto settle score: 82.745 compute time: 3 2024-04-02 08:46:18.041 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:18.071 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:18.077 AbstractSettlingCamera TRACE: autoSettleAndCapture t=72 auto settle score: 58.431 compute time: 4 2024-04-02 08:46:18.077 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 08:46:18.101 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 08:46:18.107 AbstractSettlingCamera TRACE: autoSettleAndCapture t=103 auto settle score: 16.471 compute time: 4 2024-04-02 08:46:18.108 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 104 ms 2024-04-02 08:46:18.108 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 08:46:18.145 CvPipeline DEBUG: Stage "6" throws CvException [org.opencv.core.CvException: cv::Exception: OpenCV(4.5.5) C:\build\master_winpack-bindings-win64-vc14-static\opencv\modules\imgproc\src\thresh.cpp:1555: error: (-2:Unspecified error) in function 'double __cdecl cv::threshold(const class cv::_InputArray &,const class cv::_OutputArray &,double,double,int)' > THRESH_OTSU mode: > 'src_type == CV_8UC1 || src_type == CV_16UC1' > where > 'src_type' is 16 (CV_8UC3) ] 2024-04-02 08:46:18.191 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.016056, 45.184306, -25.000000, 0.000000 mm) 2024-04-02 08:46:18.191 AbstractHeadMountable DEBUG: DOWN.moveTo((112.016056, 45.184306, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:18.196 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (112.016056, 45.140746, -25.000000, 0.000000 mm) 2024-04-02 08:46:18.196 AbstractHeadMountable DEBUG: DOWN.moveTo((112.016056, 45.140746, -25.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:18.202 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S62 G1 Y45.2376 F99 ; move to target 2024-04-02 08:46:18.203 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S62 G1 Y45.2376 F99 ; move to target 2024-04-02 08:46:18.203 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S866 G1 Y45.5933 F745 ; move to target 2024-04-02 08:46:18.204 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.204 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S866 G1 Y45.5933 F745 ; move to target 2024-04-02 08:46:18.204 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y45.1407 F276 ; move to target 2024-04-02 08:46:18.205 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.205 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y45.1407 F276 ; move to target 2024-04-02 08:46:18.205 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N1.home() 2024-04-02 08:46:18.206 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N1.home() nozzle tip N3-503 calibration reset 2024-04-02 08:46:18.206 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N2.home() 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N2.home() nozzle tip N3-503 calibration reset 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N3.home() 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N3.home() nozzle tip 504 ID1.5 calibration reset 2024-04-02 08:46:18.206 ReferenceNozzle DEBUG: N3.home() nozzle tip N3-503 calibration neeeded 2024-04-02 08:46:18.206 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.206 AbstractNozzle TRACE: Set rotation mode offset: none. 2024-04-02 08:46:18.207 Scripting TRACE: Scripting.on NozzleCalibration.Starting 2024-04-02 08:46:18.207 ReferenceHead DEBUG: H1.moveToSafeZ(0.76) 2024-04-02 08:46:18.207 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.76) 2024-04-02 08:46:18.208 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.76) 2024-04-02 08:46:18.208 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.76) 2024-04-02 08:46:18.208 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.76) 2024-04-02 08:46:18.208 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.76) 2024-04-02 08:46:18.208 AbstractHeadMountable DEBUG: DOWN.moveTo((0.000000, 0.000000, 0.000000, 0.000000 mm), 0.76) 2024-04-02 08:46:18.209 AbstractHeadMountable DEBUG: N3.moveTo((140.713000, 18.484000, 0.000000, -180.000000 mm), 0.76) 2024-04-02 08:46:18.213 AbstractHeadMountable DEBUG: N3.moveTo((140.713000, 18.484000, -24.850000, -180.000000 mm), 0.76) 2024-04-02 08:46:18.216 ReferenceNozzleTipCalibration DEBUG: [nozzleTipCalibration]starting measurement; angleStart: -180.0, angleStop: 180.0, angleIncrement: 60.0, angleSubdivisions: 5 2024-04-02 08:46:18.216 ReferenceNozzleTipCalibration DEBUG: [nozzleTipCalibration]i: 0, measureAngle: -180.0 2024-04-02 08:46:18.217 AbstractHeadMountable DEBUG: N3.moveTo((140.713000, 18.484000, -24.850000, -180.000000 mm), 0.76) 2024-04-02 08:46:18.218 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2653 G1 X102.1640 Y19.7748 B-180.00 F31508 ; move to target 2024-04-02 08:46:18.219 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2653 G1 X102.1640 Y19.7748 B-180.00 F31508 ; move to target 2024-04-02 08:46:18.219 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X102.1340 Y20.2273 F277 ; move to target 2024-04-02 08:46:18.220 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X102.1340 Y20.2273 F277 ; move to target 2024-04-02 08:46:18.220 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1444 G1 A-24.8500 F8037 ; move to target 2024-04-02 08:46:18.220 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:18.220 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1444 G1 A-24.8500 F8037 ; move to target 2024-04-02 08:46:18.220 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 08:46:18.220 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.220 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 08:46:18.221 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 08:46:18.221 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 08:46:18.222 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok