2024-04-04 06:49:55.219 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2024-03-27_09-59-31.0b0aca0. 2024-04-04 06:49:55.221 Scripting TRACE: Scripting.on Startup 2024-04-04 06:49:56.822 AbstractBroadcastingCamera TRACE: Camera DOWN thread 49 started. 2024-04-04 06:49:58.929 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 51 started. 2024-04-04 06:50:01.310 ReferenceMachine DEBUG: setEnabled(true) 2024-04-04 06:50:01.312 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-04 06:50:01.350 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-04 06:50:16.351 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-04 06:50:16.352 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-04 06:50:16.353 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:50:16.352 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-04 06:50:16.363 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-04 06:50:16.364 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-04 06:50:16.364 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-04 06:50:16.364 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-04 06:50:16.364 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-04 06:50:16.364 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:50:16.365 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:50:16.374 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-04 06:50:16.438 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller starting... 2024-04-04 06:50:16.440 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller ready. 2024-04-04 06:50:19.375 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G21 ; Set millimeters mode 2024-04-04 06:50:19.375 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-04 06:50:19.375 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-04 06:50:19.375 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G21 ; Set millimeters mode 2024-04-04 06:50:19.378 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G21 ; Set millimeters mode 2024-04-04 06:50:19.381 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-04 06:50:19.381 GcodeDriver TRACE: [Schultz:COM3] confirmed G21 ; Set millimeters mode 2024-04-04 06:50:19.381 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G90 ; Set absolute positioning mode 2024-04-04 06:50:19.385 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G90 ; Set absolute positioning mode 2024-04-04 06:50:19.388 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-04 06:50:19.389 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:01.879 ReferenceMachine DEBUG: homing machine 2024-04-04 06:51:01.881 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-04 06:51:01.881 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-04 06:51:01.881 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-04 06:51:01.881 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-04 06:51:01.882 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-04 06:51:01.882 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-04 06:51:01.882 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-04 06:51:01.882 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:01.882 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-04 06:51:01.882 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-04 06:51:01.882 GcodeDriver TRACE: [Schultz:COM3] confirmed G90 ; Set absolute positioning mode 2024-04-04 06:51:01.882 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-04 06:51:01.882 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-04 06:51:01.882 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-04 06:51:01.883 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:01.883 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-04 06:51:01.883 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:01.883 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-04 06:51:01.884 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-04 06:51:01.888 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-04 06:51:02.961 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.121 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.121 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.121 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.122 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-04 06:51:07.122 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-04 06:51:07.122 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-04 06:51:07.122 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-04 06:51:07.122 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.124 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-04 06:51:07.124 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.128 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.131 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-04 06:51:07.131 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.131 GcodeAsyncDriver TRACE: Schultz waited 9ms to drain command queue. 2024-04-04 06:51:07.131 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-04 06:51:07.132 ReferenceHead DEBUG: H1.home() 2024-04-04 06:51:07.132 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-04 06:51:07.133 ReferenceHead DEBUG: H1.moveToSafeZ(0.9) 2024-04-04 06:51:07.134 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.9) 2024-04-04 06:51:07.134 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.9) 2024-04-04 06:51:07.134 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.9) 2024-04-04 06:51:07.134 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.9) 2024-04-04 06:51:07.134 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.9) 2024-04-04 06:51:07.135 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:07.144 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:07.151 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2611 G1 X109.3513 Y42.9913 F33233 ; move to target 2024-04-04 06:51:07.152 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2611 G1 X109.3513 Y42.9913 F33233 ; move to target 2024-04-04 06:51:07.153 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X109.3213 Y43.4438 F392 ; move to target 2024-04-04 06:51:07.153 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.153 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X109.3213 Y43.4438 F392 ; move to target 2024-04-04 06:51:07.154 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.156 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.156 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-04 06:51:07.157 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.157 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-04 06:51:07.688 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.689 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:109.3213 Y:43.4438 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-04 06:51:07.689 GcodeDriver TRACE: Position report: ok C: X:109.3213 Y:43.4438 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-04 06:51:07.689 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:109.321300, y:43.443800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-04 06:51:07.690 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-04 06:51:07.690 ReferenceActuator DEBUG: UpCamLight.actuate(false) 2024-04-04 06:51:07.691 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M811 ; turn off Light 2024-04-04 06:51:07.691 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-04 06:51:07.691 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M811 ; turn off Light 2024-04-04 06:51:07.693 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.694 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:07.708 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:07.716 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:07.734 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:07.742 AbstractSettlingCamera TRACE: autoSettleAndCapture t=46 auto settle score: 93.333 compute time: 5 2024-04-04 06:51:07.742 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:07.766 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:07.774 AbstractSettlingCamera TRACE: autoSettleAndCapture t=81 auto settle score: 18.431 compute time: 7 2024-04-04 06:51:07.775 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:07.797 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:07.801 AbstractSettlingCamera TRACE: autoSettleAndCapture t=107 auto settle score: 26.275 compute time: 3 2024-04-04 06:51:07.802 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 108 ms 2024-04-04 06:51:07.802 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-04 06:51:07.823 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-04 06:51:07.866 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.652663, 44.936028, -25.000000, 0.000000 mm) 2024-04-04 06:51:07.867 AbstractHeadMountable DEBUG: DOWN.moveTo((112.652663, 44.936028, -25.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:07.872 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-04 06:51:07.875 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2588 G1 X112.0040 Y44.4273 F5160 ; move to target 2024-04-04 06:51:07.876 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2588 G1 X112.0040 Y44.4273 F5160 ; move to target 2024-04-04 06:51:07.876 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 X111.9739 Y44.8798 F392 ; move to target 2024-04-04 06:51:07.876 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:07.876 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.877 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 X111.9739 Y44.8798 F392 ; move to target 2024-04-04 06:51:07.877 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-04 06:51:07.877 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-04 06:51:07.877 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-04 06:51:07.878 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:08.051 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:08.052 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:111.9739 Y:44.8798 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-04 06:51:08.053 GcodeDriver TRACE: Position report: ok C: X:111.9739 Y:44.8798 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-04 06:51:08.053 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:111.973900, y:44.879800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-04 06:51:08.053 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-04 06:51:08.053 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:08.067 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:08.074 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:08.101 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:08.108 AbstractSettlingCamera TRACE: autoSettleAndCapture t=54 auto settle score: 98.431 compute time: 6 2024-04-04 06:51:08.108 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:08.120 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:08.127 AbstractSettlingCamera TRACE: autoSettleAndCapture t=74 auto settle score: 98.824 compute time: 4 2024-04-04 06:51:08.128 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:08.141 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:08.148 AbstractSettlingCamera TRACE: autoSettleAndCapture t=94 auto settle score: 28.235 compute time: 6 2024-04-04 06:51:08.148 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-04 06:51:08.164 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-04 06:51:08.171 AbstractSettlingCamera TRACE: autoSettleAndCapture t=117 auto settle score: 35.294 compute time: 6 2024-04-04 06:51:08.172 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 118 ms 2024-04-04 06:51:08.172 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-04 06:51:08.193 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-04 06:51:08.231 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.672607, 45.015807, -25.000000, 0.000000 mm) 2024-04-04 06:51:08.232 ReferenceFiducialLocator TRACE: FIDUCIAL-HOME less than max. linear offset 0.082mm < 0.200mm, locator satisfied. 2024-04-04 06:51:08.235 AbstractHeadMountable DEBUG: DOWN.moveTo((112.672607, 45.015807, -25.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:08.238 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X112.0239 F661 ; move to target 2024-04-04 06:51:08.238 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X112.0239 F661 ; move to target 2024-04-04 06:51:08.239 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S100 G1 X111.9939 Y44.9596 F175 ; move to target 2024-04-04 06:51:08.239 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:08.240 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S100 G1 X111.9939 Y44.9596 F175 ; move to target 2024-04-04 06:51:08.241 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:08.241 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X-0.6787 Y-0.0562 ; reset coordinates 2024-04-04 06:51:08.241 ReferenceNozzle DEBUG: N1.home() 2024-04-04 06:51:08.241 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X-0.6787 Y-0.0562 ; reset coordinates 2024-04-04 06:51:08.241 ReferenceNozzle DEBUG: N2.home() 2024-04-04 06:51:08.241 ReferenceNozzle DEBUG: N3.home() 2024-04-04 06:51:08.242 ReferenceNozzle DEBUG: N3.home() nozzle tip 504 ID1.5 calibration reset 2024-04-04 06:51:08.242 ReferenceNozzle DEBUG: N3.home() nozzle tip 506 ID5.0 calibration reset 2024-04-04 06:51:08.242 ReferenceNozzle DEBUG: N3.home() nozzle tip 505 ID3.5 calibration reset 2024-04-04 06:51:08.242 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:08.242 ReferenceNozzle DEBUG: N4.home() 2024-04-04 06:51:08.242 ReferenceNozzle DEBUG: N4.home() nozzle tip 504 ID1.5 calibration reset 2024-04-04 06:51:08.243 ReferenceNozzle DEBUG: N4.home() nozzle tip 506 ID5.0 calibration reset 2024-04-04 06:51:08.243 ReferenceNozzle DEBUG: N4.home() nozzle tip 505 ID3.5 calibration reset 2024-04-04 06:51:08.744 Scripting TRACE: Scripting.on Machine.AfterHoming 2024-04-04 06:51:08.746 ReferenceMachine INFO: setHomed(true) 2024-04-04 06:51:08.747 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:15.756 AbstractHeadMountable DEBUG: N1.moveTo((45.600259, 22.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:15.759 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X9.3513 F9367 ; move to target 2024-04-04 06:51:15.759 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X9.3513 F9367 ; move to target 2024-04-04 06:51:15.760 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X9.3213 F513 ; move to target 2024-04-04 06:51:15.760 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:15.760 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X9.3213 F513 ; move to target 2024-04-04 06:51:15.761 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:15.761 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:16.414 AbstractHeadMountable DEBUG: N1.moveTo((55.600259, 22.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:16.416 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X19.3513 F9367 ; move to target 2024-04-04 06:51:16.417 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X19.3513 F9367 ; move to target 2024-04-04 06:51:16.417 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X19.3213 F513 ; move to target 2024-04-04 06:51:16.417 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:16.417 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X19.3213 F513 ; move to target 2024-04-04 06:51:16.418 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:16.418 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:16.672 AbstractHeadMountable DEBUG: N1.moveTo((65.600259, 22.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:16.675 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X29.3513 F9367 ; move to target 2024-04-04 06:51:16.676 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X29.3513 F9367 ; move to target 2024-04-04 06:51:16.676 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X29.3213 F513 ; move to target 2024-04-04 06:51:16.676 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:16.676 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X29.3213 F513 ; move to target 2024-04-04 06:51:16.677 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:16.677 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:17.037 AbstractHeadMountable DEBUG: N1.moveTo((75.600259, 22.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:17.039 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X39.3513 F9367 ; move to target 2024-04-04 06:51:17.039 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X39.3513 F9367 ; move to target 2024-04-04 06:51:17.039 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X39.3213 F513 ; move to target 2024-04-04 06:51:17.039 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:17.040 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X39.3213 F513 ; move to target 2024-04-04 06:51:17.040 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:17.041 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:17.305 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 22.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:17.307 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X49.3513 F9367 ; move to target 2024-04-04 06:51:17.308 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X49.3213 F513 ; move to target 2024-04-04 06:51:17.308 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X49.3513 F9367 ; move to target 2024-04-04 06:51:17.308 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:17.308 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X49.3213 F513 ; move to target 2024-04-04 06:51:17.309 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:17.309 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:18.599 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 32.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:18.601 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Y9.4913 F6462 ; move to target 2024-04-04 06:51:18.601 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Y9.4913 F6462 ; move to target 2024-04-04 06:51:18.602 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y9.9438 F391 ; move to target 2024-04-04 06:51:18.602 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:18.602 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y9.9438 F391 ; move to target 2024-04-04 06:51:18.603 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:18.604 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:19.813 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 42.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:19.816 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Y19.4913 F6462 ; move to target 2024-04-04 06:51:19.816 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Y19.4913 F6462 ; move to target 2024-04-04 06:51:19.816 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y19.9438 F391 ; move to target 2024-04-04 06:51:19.816 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:19.816 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y19.9438 F391 ; move to target 2024-04-04 06:51:19.817 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:19.818 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:20.629 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 52.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:20.631 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Y29.4913 F6462 ; move to target 2024-04-04 06:51:20.631 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Y29.4913 F6462 ; move to target 2024-04-04 06:51:20.632 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y29.9438 F391 ; move to target 2024-04-04 06:51:20.632 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:20.632 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y29.9438 F391 ; move to target 2024-04-04 06:51:20.633 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:20.633 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:25.182 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:25.184 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Y129.4913 F20867 ; move to target 2024-04-04 06:51:25.185 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Y129.4913 F20867 ; move to target 2024-04-04 06:51:25.185 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y129.9438 F391 ; move to target 2024-04-04 06:51:25.185 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:25.185 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y129.9438 F391 ; move to target 2024-04-04 06:51:25.186 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:25.186 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:27.419 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:27.421 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3513 F29581 ; move to target 2024-04-04 06:51:27.421 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3513 F29581 ; move to target 2024-04-04 06:51:27.421 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:51:27.421 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:27.421 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:51:27.422 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:27.423 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:29.895 AbstractHeadMountable DEBUG: N1.moveTo((85.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:29.896 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X49.3513 F29573 ; move to target 2024-04-04 06:51:29.897 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X49.3513 F29573 ; move to target 2024-04-04 06:51:29.897 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X49.3213 F513 ; move to target 2024-04-04 06:51:29.897 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:29.897 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X49.3213 F513 ; move to target 2024-04-04 06:51:29.898 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:29.899 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:31.819 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:31.821 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3513 F29581 ; move to target 2024-04-04 06:51:31.822 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3513 F29581 ; move to target 2024-04-04 06:51:31.822 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:51:31.822 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:31.822 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:51:31.823 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:31.823 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:33.282 AbstractHeadMountable DEBUG: N1.moveTo((285.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:33.284 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X249.3513 F29581 ; move to target 2024-04-04 06:51:33.284 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X249.3513 F29581 ; move to target 2024-04-04 06:51:33.284 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X249.3213 F513 ; move to target 2024-04-04 06:51:33.284 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:33.284 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X249.3213 F513 ; move to target 2024-04-04 06:51:33.286 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:33.286 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:35.847 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:35.848 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3513 F29573 ; move to target 2024-04-04 06:51:35.849 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:51:35.849 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3513 F29573 ; move to target 2024-04-04 06:51:35.849 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:35.849 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:51:35.850 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:35.851 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:51.849 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 10.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:51.851 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Z-10.0000 F6614 ; move to target 2024-04-04 06:51:51.851 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:51.851 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Z-10.0000 F6614 ; move to target 2024-04-04 06:51:51.852 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:52.860 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 20.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:52.861 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Z-20.0000 F6614 ; move to target 2024-04-04 06:51:52.861 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:52.861 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Z-20.0000 F6614 ; move to target 2024-04-04 06:51:52.862 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:54.638 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 10.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:54.639 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Z-10.0000 F6614 ; move to target 2024-04-04 06:51:54.639 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:54.641 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:51:54.641 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Z-10.0000 F6614 ; move to target 2024-04-04 06:51:55.219 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:51:55.221 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Z0.0000 F6614 ; move to target 2024-04-04 06:51:55.221 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:51:55.221 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Z0.0000 F6614 ; move to target 2024-04-04 06:51:55.222 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:00.785 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, -10.000000 mm), 0.9) 2024-04-04 06:52:00.795 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S405 G1 B-10.0000 F1800 ; move to target 2024-04-04 06:52:00.795 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:00.795 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S405 G1 B-10.0000 F1800 ; move to target 2024-04-04 06:52:00.796 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:01.742 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, -20.000000 mm), 0.9) 2024-04-04 06:52:01.743 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S405 G1 B-20.0000 F1800 ; move to target 2024-04-04 06:52:01.743 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:01.743 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S405 G1 B-20.0000 F1800 ; move to target 2024-04-04 06:52:01.745 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:02.631 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, -30.000000 mm), 0.9) 2024-04-04 06:52:02.633 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S405 G1 B-30.0000 F1800 ; move to target 2024-04-04 06:52:02.633 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:02.633 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S405 G1 B-30.0000 F1800 ; move to target 2024-04-04 06:52:02.635 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:04.894 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, -20.000000 mm), 0.9) 2024-04-04 06:52:04.895 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S405 G1 B-20.0000 F1800 ; move to target 2024-04-04 06:52:04.896 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:04.896 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S405 G1 B-20.0000 F1800 ; move to target 2024-04-04 06:52:04.897 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:05.823 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, -10.000000 mm), 0.9) 2024-04-04 06:52:05.824 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S405 G1 B-10.0000 F1800 ; move to target 2024-04-04 06:52:05.825 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:05.825 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S405 G1 B-10.0000 F1800 ; move to target 2024-04-04 06:52:05.826 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:06.959 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:52:06.960 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S405 G1 B0.0000 F1800 ; move to target 2024-04-04 06:52:06.960 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:06.960 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S405 G1 B0.0000 F1800 ; move to target 2024-04-04 06:52:06.962 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:13.357 AbstractHeadMountable DEBUG: N1.moveTo((195.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:52:13.359 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X159.3513 F9367 ; move to target 2024-04-04 06:52:13.359 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X159.3513 F9367 ; move to target 2024-04-04 06:52:13.359 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X159.3213 F513 ; move to target 2024-04-04 06:52:13.360 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:13.360 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X159.3213 F513 ; move to target 2024-04-04 06:52:13.361 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:13.363 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:14.271 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:52:14.273 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3513 F9339 ; move to target 2024-04-04 06:52:14.273 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3513 F9339 ; move to target 2024-04-04 06:52:14.273 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:52:14.273 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:14.273 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2430 G1 X149.3213 F513 ; move to target 2024-04-04 06:52:14.275 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:14.276 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:15.048 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 142.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:52:15.052 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Y120.3430 F6480 ; move to target 2024-04-04 06:52:15.052 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Y120.3430 F6480 ; move to target 2024-04-04 06:52:15.052 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y119.8905 F391 ; move to target 2024-04-04 06:52:15.052 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:15.052 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y119.8905 F391 ; move to target 2024-04-04 06:52:15.054 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:15.055 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:15.811 AbstractHeadMountable DEBUG: N1.moveTo((185.600259, 152.600471, 0.000000, 0.000000 mm), 0.9) 2024-04-04 06:52:15.812 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1215 G1 Y129.4913 F6480 ; move to target 2024-04-04 06:52:15.813 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1215 G1 Y129.4913 F6480 ; move to target 2024-04-04 06:52:15.813 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S94 G1 Y129.9438 F391 ; move to target 2024-04-04 06:52:15.813 AbstractMachine TRACE: Machine entering idle state. 2024-04-04 06:52:15.814 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S94 G1 Y129.9438 F391 ; move to target 2024-04-04 06:52:15.815 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:15.816 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-04 06:52:24.357 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)