2024-04-02 12:59:56.748 Main INFO: Bienvenue, Bienvenido, Willkommen, Hello, Namaskar, Welkom, Bonjour to OpenPnP version 2024-03-27_09-59-31.0b0aca0. 2024-04-02 12:59:56.750 Scripting TRACE: Scripting.on Startup 2024-04-02 12:59:58.263 AbstractBroadcastingCamera TRACE: Camera DOWN thread 49 started. 2024-04-02 12:59:59.027 AbstractBroadcastingCamera TRACE: Camera Up_Logitech thread 51 started. 2024-04-02 13:00:13.377 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 13:00:13.379 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 13:00:13.404 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 13:00:28.407 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-02 13:00:28.407 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 13:00:28.408 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-02 13:00:28.408 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:28.420 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-02 13:00:28.420 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-02 13:00:28.420 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:00:28.421 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-02 13:00:28.421 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-02 13:00:28.421 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:28.421 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:28.433 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-02 13:00:28.494 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller starting... 2024-04-02 13:00:28.496 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller ready. 2024-04-02 13:00:31.434 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G21 ; Set millimeters mode 2024-04-02 13:00:31.434 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 13:00:31.434 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:00:31.434 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G21 ; Set millimeters mode 2024-04-02 13:00:31.436 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G21 ; Set millimeters mode 2024-04-02 13:00:31.439 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:00:31.440 GcodeDriver TRACE: [Schultz:COM3] confirmed G21 ; Set millimeters mode 2024-04-02 13:00:31.440 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G90 ; Set absolute positioning mode 2024-04-02 13:00:31.444 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G90 ; Set absolute positioning mode 2024-04-02 13:00:31.447 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:00:31.454 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:35.453 ReferenceMachine DEBUG: homing machine 2024-04-02 13:00:35.455 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-02 13:00:35.455 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-02 13:00:35.455 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 13:00:35.455 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 13:00:35.455 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 13:00:35.455 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-02 13:00:35.455 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 13:00:35.456 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-02 13:00:35.456 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 13:00:35.456 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:35.456 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 13:00:35.456 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:00:35.456 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 13:00:35.456 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 13:00:35.457 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-02 13:00:35.457 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 13:00:35.457 GcodeDriver TRACE: [Schultz:COM3] confirmed G90 ; Set absolute positioning mode 2024-04-02 13:00:35.457 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:00:35.457 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-02 13:00:35.457 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 13:00:35.457 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:35.457 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:00:35.457 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:35.458 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:00:35.459 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-02 13:00:35.462 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:00:36.540 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:36.540 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.945 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.946 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.946 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.946 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.946 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.947 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 13:00:45.947 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 13:00:45.947 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 13:00:45.947 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:00:45.949 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:45.949 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-02 13:00:45.949 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:45.953 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-02 13:00:45.956 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:00:45.957 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-02 13:00:45.957 GcodeAsyncDriver TRACE: Schultz waited 8ms to drain command queue. 2024-04-02 13:00:45.957 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-02 13:00:45.958 ReferenceHead DEBUG: H1.home() 2024-04-02 13:00:45.958 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-02 13:00:45.959 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-02 13:00:45.959 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-02 13:00:45.960 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-02 13:00:45.960 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-02 13:00:45.960 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-02 13:00:45.960 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-02 13:00:45.961 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:45.988 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:45.996 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2387 G1 X110.0000 Y43.6265 F22548 ; move to target 2024-04-02 13:00:45.996 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2387 G1 X110.0000 Y43.6265 F22548 ; move to target 2024-04-02 13:00:45.997 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.998 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y43.5000 F6000 ; move to target 2024-04-02 13:00:45.998 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y43.5000 F6000 ; move to target 2024-04-02 13:00:45.999 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:45.999 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:45.999 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:45.999 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:00:46.000 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:00:46.514 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:46.515 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:110.0000 Y:43.5000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:00:46.516 GcodeDriver TRACE: Position report: ok C: X:110.0000 Y:43.5000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:00:46.516 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:110.000000, y:43.500000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:00:46.516 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:00:46.517 ReferenceActuator DEBUG: UpCamLight.actuate(false) 2024-04-02 13:00:46.517 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M811 ; turn off Light 2024-04-02 13:00:46.517 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 13:00:46.518 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M811 ; turn off Light 2024-04-02 13:00:46.518 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:46.518 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.528 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.533 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.558 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.565 AbstractSettlingCamera TRACE: autoSettleAndCapture t=44 auto settle score: 60.392 compute time: 4 2024-04-02 13:00:46.565 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.590 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.595 AbstractSettlingCamera TRACE: autoSettleAndCapture t=76 auto settle score: 58.431 compute time: 4 2024-04-02 13:00:46.595 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.620 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.627 AbstractSettlingCamera TRACE: autoSettleAndCapture t=108 auto settle score: 33.725 compute time: 6 2024-04-02 13:00:46.627 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 108 ms 2024-04-02 13:00:46.627 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 13:00:46.662 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 13:00:46.715 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.145718, 45.095754, -25.000000, 0.000000 mm) 2024-04-02 13:00:46.716 AbstractHeadMountable DEBUG: DOWN.moveTo((112.145718, 45.095754, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:46.730 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 13:00:46.732 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1950 G1 X112.1457 Y45.2222 F6000 ; move to target 2024-04-02 13:00:46.733 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1950 G1 X112.1457 Y45.2222 F6000 ; move to target 2024-04-02 13:00:46.734 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.0958 F6000 ; move to target 2024-04-02 13:00:46.734 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:46.734 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.0958 F6000 ; move to target 2024-04-02 13:00:46.734 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:46.734 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:00:46.734 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:46.734 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:00:46.735 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:46.855 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:46.856 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:112.1457 Y:45.0958 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:00:46.856 GcodeDriver TRACE: Position report: ok C: X:112.1457 Y:45.0958 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:00:46.856 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.145700, y:45.095800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:00:46.856 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:00:46.856 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.869 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.872 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.885 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.891 AbstractSettlingCamera TRACE: autoSettleAndCapture t=34 auto settle score: 64.706 compute time: 5 2024-04-02 13:00:46.891 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.926 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.930 AbstractSettlingCamera TRACE: autoSettleAndCapture t=74 auto settle score: 62.745 compute time: 4 2024-04-02 13:00:46.931 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:00:46.957 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:00:46.962 AbstractSettlingCamera TRACE: autoSettleAndCapture t=106 auto settle score: 48.627 compute time: 4 2024-04-02 13:00:46.962 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 106 ms 2024-04-02 13:00:46.963 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 13:00:46.998 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 13:00:47.054 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.184731, 45.095754, -25.000000, 0.000000 mm) 2024-04-02 13:00:47.054 ReferenceFiducialLocator TRACE: FIDUCIAL-HOME less than max. linear offset 0.039mm < 0.050mm, locator satisfied. 2024-04-02 13:00:47.059 AbstractHeadMountable DEBUG: DOWN.moveTo((112.184731, 45.095754, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:47.066 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S400 G1 X112.1847 F6000 ; move to target 2024-04-02 13:00:47.067 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S400 G1 X112.1847 F6000 ; move to target 2024-04-02 13:00:47.068 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-02 13:00:47.068 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:47.068 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-02 13:00:47.068 ReferenceNozzle DEBUG: N1.home() 2024-04-02 13:00:47.068 ReferenceNozzle DEBUG: N2.home() 2024-04-02 13:00:47.068 ReferenceNozzle DEBUG: N3.home() 2024-04-02 13:00:47.068 ReferenceNozzle DEBUG: N4.home() 2024-04-02 13:00:47.068 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:47.565 Scripting TRACE: Scripting.on Machine.AfterHoming 2024-04-02 13:00:47.567 ReferenceMachine INFO: setHomed(true) 2024-04-02 13:00:47.568 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:52.054 AbstractHeadMountable DEBUG: N1.moveTo((136.279000, 22.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:52.058 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X100.0000 F19984 ; move to target 2024-04-02 13:00:52.059 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X100.0000 F19984 ; move to target 2024-04-02 13:00:52.059 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:52.059 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:52.059 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:00:52.059 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:00:52.059 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:00:52.060 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:52.510 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:52.511 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:100.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:00:52.512 GcodeDriver TRACE: Position report: ok C: X:100.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:00:52.512 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:100.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:00:52.513 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:00:52.514 ReferenceActuator DEBUG: UpCamLight.actuate(true) 2024-04-02 13:00:52.514 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M810 ; turn on Light 2024-04-02 13:00:52.515 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M810 ; turn on Light 2024-04-02 13:00:52.515 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:52.663 AbstractHeadMountable DEBUG: N1.moveTo((236.279000, 22.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:52.666 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X200.0000 F19984 ; move to target 2024-04-02 13:00:52.666 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X200.0000 F19984 ; move to target 2024-04-02 13:00:52.667 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:52.668 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:53.221 AbstractHeadMountable DEBUG: N1.moveTo((336.279000, 22.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:53.224 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-02 13:00:53.224 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-02 13:00:53.224 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:53.225 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:54.102 AbstractHeadMountable DEBUG: N1.moveTo((436.279000, 22.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:54.105 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X400.0000 F19984 ; move to target 2024-04-02 13:00:54.106 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X400.0000 F19984 ; move to target 2024-04-02 13:00:54.106 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:54.107 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:55.057 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 22.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:55.059 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X500.0000 F19984 ; move to target 2024-04-02 13:00:55.060 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X500.0000 F19984 ; move to target 2024-04-02 13:00:55.060 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:55.061 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:57.050 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:00:57.054 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y100.1265 F18255 ; move to target 2024-04-02 13:00:57.055 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y100.1265 F18255 ; move to target 2024-04-02 13:00:57.055 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:00:57.055 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:00:57.056 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:00:57.056 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:00:57.057 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:03.518 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:03.521 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:01:03.521 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:01:03.521 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:03.522 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:04.185 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:04.188 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z-20.0000 F6000 ; move to target 2024-04-02 13:01:04.188 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z-20.0000 F6000 ; move to target 2024-04-02 13:01:04.189 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:04.189 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:06.390 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:06.392 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:01:06.393 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:01:06.393 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:06.394 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:06.907 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:06.909 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:01:06.910 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:01:06.910 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:06.911 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:07.300 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, -10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:07.303 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z10.0000 F6000 ; move to target 2024-04-02 13:01:07.304 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z10.0000 F6000 ; move to target 2024-04-02 13:01:07.305 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:07.305 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:08.181 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, -20.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:08.183 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z20.0000 F6000 ; move to target 2024-04-02 13:01:08.183 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z20.0000 F6000 ; move to target 2024-04-02 13:01:08.184 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:08.184 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:10.026 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-02 13:01:10.026 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-02 13:01:10.027 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, -1.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:10.028 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-02 13:01:10.028 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-02 13:01:10.029 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-02 13:01:10.029 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-02 13:01:10.029 AbstractHeadMountable DEBUG: DOWN.moveTo((500.000000, 100.000000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:10.030 AbstractHeadMountable DEBUG: N1.moveTo((536.279000, 122.710000, -1.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:10.031 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Z1.0000 F7952 ; move to target 2024-04-02 13:01:10.031 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Z1.0000 F7952 ; move to target 2024-04-02 13:01:10.032 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:10.032 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:16.131 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:16.133 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:01:16.134 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:01:16.134 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:16.135 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:16.733 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:16.736 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A20.0000 F6000 ; move to target 2024-04-02 13:01:16.736 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:16.736 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A20.0000 F6000 ; move to target 2024-04-02 13:01:16.737 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:18.282 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:18.286 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:01:18.286 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:01:18.287 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:18.287 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:18.859 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:18.861 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:01:18.862 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:18.862 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:01:18.863 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:24.841 AbstractHeadMountable DEBUG: N4.moveTo((568.279000, 98.110000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:24.844 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:01:24.845 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:01:24.845 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:24.846 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:25.455 AbstractHeadMountable DEBUG: N4.moveTo((568.279000, 98.110000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:25.457 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A-20.0000 F6000 ; move to target 2024-04-02 13:01:25.457 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:25.458 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A-20.0000 F6000 ; move to target 2024-04-02 13:01:25.458 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:26.670 AbstractHeadMountable DEBUG: N4.moveTo((568.279000, 98.110000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:26.673 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:01:26.673 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:01:26.674 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:26.674 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:27.245 AbstractHeadMountable DEBUG: N4.moveTo((568.279000, 98.110000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:27.247 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:01:27.247 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:01:27.247 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:27.248 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:31.407 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 11.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:31.410 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z11.0000 F6000 ; move to target 2024-04-02 13:01:31.410 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z11.0000 F6000 ; move to target 2024-04-02 13:01:31.410 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:31.411 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:31.969 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 21.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:31.971 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z21.0000 F6000 ; move to target 2024-04-02 13:01:31.972 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z21.0000 F6000 ; move to target 2024-04-02 13:01:31.972 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:31.972 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:32.990 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 11.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:32.992 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z11.0000 F6000 ; move to target 2024-04-02 13:01:32.993 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z11.0000 F6000 ; move to target 2024-04-02 13:01:32.993 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:32.994 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:33.553 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 1.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:33.555 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z1.0000 F6000 ; move to target 2024-04-02 13:01:33.556 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z1.0000 F6000 ; move to target 2024-04-02 13:01:33.556 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:33.557 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:34.781 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-02 13:01:34.781 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-02 13:01:34.781 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-02 13:01:34.781 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-02 13:01:34.781 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-02 13:01:34.781 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-02 13:01:34.782 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:34.784 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S797 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:01:34.784 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S797 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:01:34.784 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:34.785 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:43.917 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:01:43.919 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S6656 G1 C-10.00 F10946 ; move to target 2024-04-02 13:01:43.920 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:43.920 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S6656 G1 C-10.00 F10946 ; move to target 2024-04-02 13:01:43.921 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:48.535 AbstractHeadMountable DEBUG: N2.moveTo((567.779000, 123.210000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:48.537 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S6656 G1 C0.00 F10946 ; move to target 2024-04-02 13:01:48.537 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:48.537 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S6656 G1 C0.00 F10946 ; move to target 2024-04-02 13:01:48.539 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:54.967 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:01:54.970 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S6656 G1 B-10.00 F10946 ; move to target 2024-04-02 13:01:54.971 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S6656 G1 B-10.00 F10946 ; move to target 2024-04-02 13:01:54.971 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:54.972 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:01:57.029 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:01:57.031 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S6656 G1 B0.00 F10946 ; move to target 2024-04-02 13:01:57.032 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S6656 G1 B0.00 F10946 ; move to target 2024-04-02 13:01:57.032 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:01:57.033 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:15.194 AbstractHeadMountable DEBUG: N3.moveTo((548.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:02:15.196 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X510.0000 F6320 ; move to target 2024-04-02 13:02:15.197 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:02:15.197 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X510.0000 F6320 ; move to target 2024-04-02 13:02:15.198 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:16.782 AbstractHeadMountable DEBUG: N3.moveTo((538.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:02:16.785 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X500.0000 F6320 ; move to target 2024-04-02 13:02:16.786 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X500.0000 F6320 ; move to target 2024-04-02 13:02:16.786 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:02:16.788 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:20.558 AbstractHeadMountable DEBUG: N3.moveTo((528.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:02:20.560 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X490.0000 F6320 ; move to target 2024-04-02 13:02:20.561 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X490.0000 F6320 ; move to target 2024-04-02 13:02:20.561 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:02:20.563 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:24.839 AbstractHeadMountable DEBUG: N3.moveTo((528.579000, 88.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:02:24.842 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:02:24.843 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:02:24.843 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:02:24.843 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:02:24.843 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:02:24.845 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:24.846 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:26.080 AbstractHeadMountable DEBUG: N3.moveTo((528.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:02:26.083 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:02:26.084 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:02:26.084 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:02:26.084 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:02:26.085 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:02:26.086 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:02:26.087 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:17:39.943 ReferenceMachine DEBUG: setEnabled(false) 2024-04-02 13:17:39.943 ReferenceMachine INFO: setHomed(false) 2024-04-02 13:17:39.944 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] Read error while disconnecting (expected) 2024-04-02 13:17:40.046 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] disconnectRequested, bye-bye. 2024-04-02 13:17:40.046 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] Read error while disconnecting (expected) 2024-04-02 13:17:40.147 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] disconnectRequested, bye-bye. 2024-04-02 13:17:40.158 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:17:45.321 ReferenceMachine DEBUG: setEnabled(true) 2024-04-02 13:17:45.321 GcodeDriver DEBUG: [Smoothie:COM4] Connect 2024-04-02 13:17:45.338 GcodeDriver TRACE: Smoothie waiting for connection 15000ms 2024-04-02 13:18:00.340 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G21 ; 2024-04-02 13:18:00.340 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 13:18:00.340 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M82 ; Set absolute mode for extruder 2024-04-02 13:18:00.340 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:18:00.340 GcodeDriver DEBUG: [Schultz:COM3] Connect 2024-04-02 13:18:00.340 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G21 ; 2024-04-02 13:18:00.340 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:00.340 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G90 ; Set absolute positioning mode 2024-04-02 13:18:00.340 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M82 ; Set absolute mode for extruder 2024-04-02 13:18:00.340 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:00.340 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:00.355 GcodeDriver TRACE: Schultz waiting for connection 3000ms 2024-04-02 13:18:00.413 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller starting... 2024-04-02 13:18:00.415 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << Controller ready. 2024-04-02 13:18:03.356 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G21 ; Set millimeters mode 2024-04-02 13:18:03.356 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G90 ; Set absolute positioning mode 2024-04-02 13:18:03.356 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:18:03.356 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G21 ; Set millimeters mode 2024-04-02 13:18:03.359 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G21 ; Set millimeters mode 2024-04-02 13:18:03.362 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:18:03.362 GcodeDriver TRACE: [Schultz:COM3] confirmed G21 ; Set millimeters mode 2024-04-02 13:18:03.362 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G90 ; Set absolute positioning mode 2024-04-02 13:18:03.365 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:03.365 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G90 ; Set absolute positioning mode 2024-04-02 13:18:03.369 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:18:05.572 ReferenceMachine DEBUG: homing machine 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1000 ; set a low acceleration 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 A0 Z0 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 13:18:05.573 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 2024-04-02 13:18:05.573 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1000 ; set a low acceleration 2024-04-02 13:18:05.573 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:18:05.574 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 A0 Z0 2024-04-02 13:18:05.574 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> G28 ; Home all axes 2024-04-02 13:18:05.574 GcodeDriver TRACE: Send-on-change logic for Feed Rate, Acceleration and Jerk reset 2024-04-02 13:18:05.574 GcodeDriver TRACE: [Schultz:COM3] confirmed G90 ; Set absolute positioning mode 2024-04-02 13:18:05.574 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:05.574 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:18:05.574 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:05.574 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 13:18:05.574 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> G28 ; Home all axes 2024-04-02 13:18:05.574 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G28 X88 Y42 B0 C0; Home all axes 2024-04-02 13:18:05.575 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 13:18:05.575 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0 Y0 Z0 A0 B0 C0; Set coordinates to zero. 2024-04-02 13:18:05.575 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 2024-04-02 13:18:05.575 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:05.575 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:18:05.576 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << G28 ; Home all axes 2024-04-02 13:18:05.579 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:18:06.655 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:06.655 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.923 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.923 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.923 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.923 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.924 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.925 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 13:18:15.925 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 13:18:15.925 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 13:18:15.925 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:18:15.925 GcodeAsyncDriver DEBUG: [Schultz:COM3] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:15.925 GcodeDriver TRACE: [Schultz:COM3] confirmed G28 ; Home all axes 2024-04-02 13:18:15.926 GcodeAsyncDriver$WriterThread TRACE: [Schultz:COM3] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:15.930 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << M400 ; Wait for moves to complete before returning 2024-04-02 13:18:15.934 GcodeDriver$ReaderThread TRACE: [Schultz:COM3] << ok unknown or empty command ignored 2024-04-02 13:18:15.934 GcodeDriver TRACE: [Schultz:COM3] confirmed M400 ; Wait for moves to complete before returning 2024-04-02 13:18:15.934 GcodeAsyncDriver TRACE: Schultz waited 9ms to drain command queue. 2024-04-02 13:18:15.934 GcodeAsyncDriver TRACE: Schultz confirmation complete. 2024-04-02 13:18:15.934 AbstractMotionPlanner DEBUG: Reported location changes current location from (x:490.000000, y:100.000000, 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 13:18:15.934 ReferenceHead DEBUG: H1.home() 2024-04-02 13:18:15.935 ReferenceFiducialLocator DEBUG: Looking for FIDUCIAL-HOME at (110.000000, 43.500000, -25.000000, 0.000000 mm) 2024-04-02 13:18:15.935 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-02 13:18:15.935 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-02 13:18:15.935 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-02 13:18:15.935 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-02 13:18:15.935 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-02 13:18:15.935 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-02 13:18:15.936 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:15.938 AbstractHeadMountable DEBUG: DOWN.moveTo((110.000000, 43.500000, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:15.939 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2387 G1 X110.0000 Y43.6265 F22548 ; move to target 2024-04-02 13:18:15.939 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2387 G1 X110.0000 Y43.6265 F22548 ; move to target 2024-04-02 13:18:15.939 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y43.5000 F6000 ; move to target 2024-04-02 13:18:15.939 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:15.939 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y43.5000 F6000 ; move to target 2024-04-02 13:18:15.940 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:18:15.940 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:15.940 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:18:15.940 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:15.941 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:16.456 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:16.457 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:110.0000 Y:43.5000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:16.457 GcodeDriver TRACE: Position report: ok C: X:110.0000 Y:43.5000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:16.457 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:110.000000, y:43.500000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:18:16.458 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:18:16.458 ReferenceActuator DEBUG: UpCamLight.actuate(false) 2024-04-02 13:18:16.458 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M811 ; turn off Light 2024-04-02 13:18:16.458 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 13:18:16.458 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M811 ; turn off Light 2024-04-02 13:18:16.459 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:16.459 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.469 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.473 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.490 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.494 AbstractSettlingCamera TRACE: autoSettleAndCapture t=36 auto settle score: 49.412 compute time: 4 2024-04-02 13:18:16.495 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.538 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.543 AbstractSettlingCamera TRACE: autoSettleAndCapture t=84 auto settle score: 49.412 compute time: 4 2024-04-02 13:18:16.543 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.571 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.575 AbstractSettlingCamera TRACE: autoSettleAndCapture t=117 auto settle score: 23.922 compute time: 4 2024-04-02 13:18:16.576 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 117 ms 2024-04-02 13:18:16.576 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 13:18:16.622 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 13:18:16.684 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.184731, 45.056833, -25.000000, 0.000000 mm) 2024-04-02 13:18:16.684 AbstractHeadMountable DEBUG: DOWN.moveTo((112.184731, 45.056833, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:16.688 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 13:18:16.690 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1931 G1 X112.1847 Y45.1833 F6000 ; move to target 2024-04-02 13:18:16.690 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1931 G1 X112.1847 Y45.1833 F6000 ; move to target 2024-04-02 13:18:16.690 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.0568 F6000 ; move to target 2024-04-02 13:18:16.690 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:16.690 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:18:16.690 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.0568 F6000 ; move to target 2024-04-02 13:18:16.691 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:16.691 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:16.691 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:18:16.692 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:16.812 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:16.813 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:112.1847 Y:45.0568 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:16.813 GcodeDriver TRACE: Position report: ok C: X:112.1847 Y:45.0568 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:16.813 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.184700, y:45.056800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:18:16.814 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:18:16.814 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.826 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.830 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.840 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.844 AbstractSettlingCamera TRACE: autoSettleAndCapture t=30 auto settle score: 51.765 compute time: 3 2024-04-02 13:18:16.845 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.859 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.864 AbstractSettlingCamera TRACE: autoSettleAndCapture t=50 auto settle score: 45.882 compute time: 5 2024-04-02 13:18:16.864 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.890 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.895 AbstractSettlingCamera TRACE: autoSettleAndCapture t=82 auto settle score: 36.078 compute time: 4 2024-04-02 13:18:16.896 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:16.939 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:16.944 AbstractSettlingCamera TRACE: autoSettleAndCapture t=131 auto settle score: 18.431 compute time: 5 2024-04-02 13:18:16.945 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 131 ms 2024-04-02 13:18:16.945 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 13:18:16.985 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 13:18:17.038 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.106705, 45.095754, -25.000000, 0.000000 mm) 2024-04-02 13:18:17.038 AbstractHeadMountable DEBUG: DOWN.moveTo((112.106705, 45.095754, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:17.043 Scripting TRACE: Scripting.on Camera.BeforeSettle 2024-04-02 13:18:17.044 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1181 G1 X112.1067 Y45.2222 F6000 ; move to target 2024-04-02 13:18:17.044 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1181 G1 X112.1067 Y45.2222 F6000 ; move to target 2024-04-02 13:18:17.044 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.0958 F6000 ; move to target 2024-04-02 13:18:17.044 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:17.044 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.0958 F6000 ; move to target 2024-04-02 13:18:17.044 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:18:17.044 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:17.045 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:18:17.045 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.045 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.118 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.119 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:112.1067 Y:45.0958 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:17.120 GcodeDriver TRACE: Position report: ok C: X:112.1067 Y:45.0958 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:17.120 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:112.106700, y:45.095800, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:18:17.120 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:18:17.120 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:17.132 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:17.137 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:17.149 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:17.155 AbstractSettlingCamera TRACE: autoSettleAndCapture t=35 auto settle score: 43.137 compute time: 5 2024-04-02 13:18:17.155 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:17.167 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:17.172 AbstractSettlingCamera TRACE: autoSettleAndCapture t=52 auto settle score: 24.314 compute time: 4 2024-04-02 13:18:17.172 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:17.194 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:17.199 AbstractSettlingCamera TRACE: autoSettleAndCapture t=80 auto settle score: 16.863 compute time: 5 2024-04-02 13:18:17.199 Scripting TRACE: Scripting.on Camera.BeforeCapture 2024-04-02 13:18:17.226 Scripting TRACE: Scripting.on Camera.AfterCapture 2024-04-02 13:18:17.233 AbstractSettlingCamera TRACE: autoSettleAndCapture t=114 auto settle score: 13.333 compute time: 6 2024-04-02 13:18:17.233 AbstractSettlingCamera DEBUG: autoSettleAndCapture in 114 ms 2024-04-02 13:18:17.234 Scripting TRACE: Scripting.on Camera.AfterSettle 2024-04-02 13:18:17.275 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 13:18:17.328 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME located at (112.028679, 45.056833, -25.000000, 0.000000 mm) 2024-04-02 13:18:17.328 AbstractHeadMountable DEBUG: DOWN.moveTo((112.028679, 45.056833, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:17.333 ReferenceFiducialLocator DEBUG: FIDUCIAL-HOME averaged location is at (112.067692, 45.076293, -25.000000, 0.000000 mm) 2024-04-02 13:18:17.333 AbstractHeadMountable DEBUG: DOWN.moveTo((112.067692, 45.076293, -25.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:17.336 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S757 G1 X112.0287 Y45.1833 F6000 ; move to target 2024-04-02 13:18:17.337 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S757 G1 X112.0287 Y45.1833 F6000 ; move to target 2024-04-02 13:18:17.337 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.0568 F6000 ; move to target 2024-04-02 13:18:17.337 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.0568 F6000 ; move to target 2024-04-02 13:18:17.337 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1548 G1 X112.0677 Y45.2028 F6000 ; move to target 2024-04-02 13:18:17.338 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.338 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y45.0763 F6000 ; move to target 2024-04-02 13:18:17.338 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1548 G1 X112.0677 Y45.2028 F6000 ; move to target 2024-04-02 13:18:17.338 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-02 13:18:17.338 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y45.0763 F6000 ; move to target 2024-04-02 13:18:17.338 ReferenceNozzle DEBUG: N1.home() 2024-04-02 13:18:17.338 ReferenceNozzle DEBUG: N2.home() 2024-04-02 13:18:17.338 ReferenceNozzle DEBUG: N3.home() 2024-04-02 13:18:17.338 ReferenceNozzle DEBUG: N4.home() 2024-04-02 13:18:17.339 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> G92 X0.0000 Y0.0000 ; reset coordinates 2024-04-02 13:18:17.339 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.340 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.341 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.341 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:17.861 Scripting TRACE: Scripting.on Machine.AfterHoming 2024-04-02 13:18:17.862 ReferenceMachine INFO: setHomed(true) 2024-04-02 13:18:17.863 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:23.225 AbstractHeadMountable DEBUG: N3.moveTo((138.579000, -1.690000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:23.228 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X100.0000 F19984 ; move to target 2024-04-02 13:18:23.228 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:23.228 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X100.0000 F19984 ; move to target 2024-04-02 13:18:23.228 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:23.229 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M114 ; get position 2024-04-02 13:18:23.229 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M400 ; Wait for moves to complete before returning 2024-04-02 13:18:23.229 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M114 ; get position 2024-04-02 13:18:23.229 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:23.680 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:23.681 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok C: X:100.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:23.681 GcodeDriver TRACE: Position report: ok C: X:100.0000 Y:0.0000 Z:0.0000 A:0.0000 B:0.0000 C:0.0000 2024-04-02 13:18:23.682 GcodeDriver TRACE: Smoothie got lastReportedLocation (x:100.000000, y:0.000000, z1:0.000000, z2:0.000000, c1:0.000000, c2:0.000000) 2024-04-02 13:18:23.682 GcodeAsyncDriver TRACE: Smoothie confirmation complete. 2024-04-02 13:18:23.682 ReferenceActuator DEBUG: UpCamLight.actuate(true) 2024-04-02 13:18:23.683 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M810 ; turn on Light 2024-04-02 13:18:23.683 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M810 ; turn on Light 2024-04-02 13:18:23.684 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:23.794 AbstractHeadMountable DEBUG: N3.moveTo((238.579000, -1.690000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:23.794 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X200.0000 F19984 ; move to target 2024-04-02 13:18:23.795 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:23.795 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X200.0000 F19984 ; move to target 2024-04-02 13:18:23.796 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:24.467 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, -1.690000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:24.468 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-02 13:18:24.469 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:24.469 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-02 13:18:24.470 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:25.347 AbstractHeadMountable DEBUG: N3.moveTo((438.579000, -1.690000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:25.349 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X400.0000 F19984 ; move to target 2024-04-02 13:18:25.349 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X400.0000 F19984 ; move to target 2024-04-02 13:18:25.349 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:25.350 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:27.525 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, -1.690000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:27.527 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-02 13:18:27.528 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:27.528 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X300.0000 F19984 ; move to target 2024-04-02 13:18:27.529 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:30.354 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 8.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:30.357 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y10.1265 F6000 ; move to target 2024-04-02 13:18:30.357 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y10.1265 F6000 ; move to target 2024-04-02 13:18:30.357 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y10.0000 F6000 ; move to target 2024-04-02 13:18:30.358 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y10.0000 F6000 ; move to target 2024-04-02 13:18:30.358 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:30.358 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:30.359 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:30.886 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 18.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:30.889 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y20.1265 F6000 ; move to target 2024-04-02 13:18:30.890 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y20.1265 F6000 ; move to target 2024-04-02 13:18:30.890 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y20.0000 F6000 ; move to target 2024-04-02 13:18:30.890 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y20.0000 F6000 ; move to target 2024-04-02 13:18:30.890 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:30.891 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:30.892 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.123 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 28.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:31.126 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y30.1265 F6000 ; move to target 2024-04-02 13:18:31.126 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y30.1265 F6000 ; move to target 2024-04-02 13:18:31.128 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.128 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y30.0000 F6000 ; move to target 2024-04-02 13:18:31.128 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y30.0000 F6000 ; move to target 2024-04-02 13:18:31.128 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:31.129 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.331 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 38.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:31.334 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y40.1265 F6000 ; move to target 2024-04-02 13:18:31.334 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y40.0000 F6000 ; move to target 2024-04-02 13:18:31.335 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y40.1265 F6000 ; move to target 2024-04-02 13:18:31.335 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y40.0000 F6000 ; move to target 2024-04-02 13:18:31.335 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:31.336 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.337 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.493 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 48.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:31.496 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y50.1265 F6000 ; move to target 2024-04-02 13:18:31.496 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y50.1265 F6000 ; move to target 2024-04-02 13:18:31.496 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y50.0000 F6000 ; move to target 2024-04-02 13:18:31.497 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y50.0000 F6000 ; move to target 2024-04-02 13:18:31.497 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:31.497 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.499 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.672 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 58.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:31.675 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y60.1265 F6000 ; move to target 2024-04-02 13:18:31.675 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y60.0000 F6000 ; move to target 2024-04-02 13:18:31.675 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y60.1265 F6000 ; move to target 2024-04-02 13:18:31.676 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:31.676 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y60.0000 F6000 ; move to target 2024-04-02 13:18:31.677 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.678 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.855 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 68.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:31.858 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y70.1265 F6000 ; move to target 2024-04-02 13:18:31.858 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y70.0000 F6000 ; move to target 2024-04-02 13:18:31.858 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y70.1265 F6000 ; move to target 2024-04-02 13:18:31.859 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:31.859 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y70.0000 F6000 ; move to target 2024-04-02 13:18:31.860 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:31.861 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.029 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 78.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:32.034 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y80.1265 F6000 ; move to target 2024-04-02 13:18:32.034 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y80.1265 F6000 ; move to target 2024-04-02 13:18:32.034 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y80.0000 F6000 ; move to target 2024-04-02 13:18:32.034 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y80.0000 F6000 ; move to target 2024-04-02 13:18:32.035 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:32.035 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.036 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.216 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 88.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:32.219 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:18:32.220 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:18:32.220 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:18:32.220 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:18:32.220 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:32.221 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.222 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.378 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:32.382 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:18:32.383 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:18:32.383 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:18:32.383 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:18:32.383 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:32.384 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.385 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.578 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 108.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:32.581 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:18:32.581 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:18:32.581 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:18:32.582 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:18:32.582 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:32.583 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.584 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.755 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 118.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:32.758 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y120.1265 F6000 ; move to target 2024-04-02 13:18:32.758 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y120.0000 F6000 ; move to target 2024-04-02 13:18:32.759 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y120.1265 F6000 ; move to target 2024-04-02 13:18:32.759 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:32.759 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y120.0000 F6000 ; move to target 2024-04-02 13:18:32.760 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:32.761 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.142 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 108.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:34.147 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:18:34.147 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:18:34.147 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:18:34.148 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:18:34.148 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:34.148 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.149 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.383 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:34.387 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:18:34.387 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:18:34.387 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:18:34.388 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:18:34.388 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:34.388 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.389 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.591 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 88.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:34.594 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:18:34.595 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:18:34.595 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:18:34.595 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:18:34.595 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:34.596 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.597 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.810 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 78.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:34.813 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y80.1265 F6000 ; move to target 2024-04-02 13:18:34.813 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y80.1265 F6000 ; move to target 2024-04-02 13:18:34.813 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y80.0000 F6000 ; move to target 2024-04-02 13:18:34.813 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:34.813 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y80.0000 F6000 ; move to target 2024-04-02 13:18:34.814 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:34.815 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:35.808 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 88.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:35.811 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:18:35.811 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:18:35.811 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y90.1265 F6000 ; move to target 2024-04-02 13:18:35.811 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:35.812 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y90.0000 F6000 ; move to target 2024-04-02 13:18:35.812 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:35.813 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:36.009 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:36.012 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:18:36.013 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:18:36.013 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y100.1265 F6000 ; move to target 2024-04-02 13:18:36.013 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y100.0000 F6000 ; move to target 2024-04-02 13:18:36.013 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:36.014 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:36.015 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:37.706 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:37.707 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:18:37.708 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:18:37.708 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:37.709 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:37.945 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:37.947 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A20.0000 F6000 ; move to target 2024-04-02 13:18:37.947 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A20.0000 F6000 ; move to target 2024-04-02 13:18:37.947 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:37.948 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:39.221 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:39.223 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:18:39.223 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:39.224 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:18:39.224 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:39.427 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:39.429 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:18:39.429 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:39.429 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:18:39.430 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:39.637 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, -10.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:39.638 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:18:39.639 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:39.639 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:18:39.640 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:40.400 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, -20.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:40.402 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A-20.0000 F6000 ; move to target 2024-04-02 13:18:40.403 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A-20.0000 F6000 ; move to target 2024-04-02 13:18:40.403 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:40.403 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:42.054 ReferenceHead DEBUG: H1.moveToSafeZ(0.86) 2024-04-02 13:18:42.055 AbstractHeadMountable DEBUG: N1.moveToSafeZ(0.86) 2024-04-02 13:18:42.055 AbstractHeadMountable DEBUG: N2.moveToSafeZ(0.86) 2024-04-02 13:18:42.055 AbstractHeadMountable DEBUG: N3.moveToSafeZ(0.86) 2024-04-02 13:18:42.055 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:42.056 AbstractHeadMountable DEBUG: N4.moveToSafeZ(0.86) 2024-04-02 13:18:42.056 AbstractHeadMountable DEBUG: DOWN.moveToSafeZ(0.86) 2024-04-02 13:18:42.056 AbstractHeadMountable DEBUG: DOWN.moveTo((300.000000, 100.000000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:42.057 AbstractHeadMountable DEBUG: N3.moveTo((338.579000, 98.310000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:42.058 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 A0.0000 F8159 ; move to target 2024-04-02 13:18:42.058 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:42.058 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 A0.0000 F8159 ; move to target 2024-04-02 13:18:42.059 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:48.772 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 122.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:48.775 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X310.0000 F6320 ; move to target 2024-04-02 13:18:48.775 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X310.0000 F6320 ; move to target 2024-04-02 13:18:48.775 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:48.776 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:49.646 AbstractHeadMountable DEBUG: N1.moveTo((356.279000, 122.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:49.648 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X320.0000 F6320 ; move to target 2024-04-02 13:18:49.649 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X320.0000 F6320 ; move to target 2024-04-02 13:18:49.649 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:49.650 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:51.065 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 122.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:51.067 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X310.0000 F6320 ; move to target 2024-04-02 13:18:51.067 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:51.067 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X310.0000 F6320 ; move to target 2024-04-02 13:18:51.068 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:51.692 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:51.695 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:18:51.695 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:18:51.695 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:18:51.695 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:51.695 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:18:51.696 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:51.697 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:56.278 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:56.280 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:18:56.280 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:18:56.280 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:56.281 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:56.615 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:56.617 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z-20.0000 F6000 ; move to target 2024-04-02 13:18:56.618 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:56.618 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z-20.0000 F6000 ; move to target 2024-04-02 13:18:56.619 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:58.183 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:58.185 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:18:58.185 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:58.185 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z-10.0000 F6000 ; move to target 2024-04-02 13:18:58.186 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:58.391 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:58.393 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:18:58.393 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:18:58.393 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:58.394 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:58.638 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, -10.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:58.640 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z10.0000 F6000 ; move to target 2024-04-02 13:18:58.640 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z10.0000 F6000 ; move to target 2024-04-02 13:18:58.640 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:58.641 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:18:58.920 AbstractHeadMountable DEBUG: N1.moveTo((346.279000, 132.710000, -20.000000, 0.000000 mm), 0.86) 2024-04-02 13:18:58.922 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z20.0000 F6000 ; move to target 2024-04-02 13:18:58.922 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z20.0000 F6000 ; move to target 2024-04-02 13:18:58.922 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:18:58.923 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:04.869 AbstractHeadMountable DEBUG: N2.moveTo((377.779000, 133.210000, 30.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:04.871 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z30.0000 F6000 ; move to target 2024-04-02 13:19:04.871 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:04.871 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z30.0000 F6000 ; move to target 2024-04-02 13:19:04.872 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:06.498 AbstractHeadMountable DEBUG: N2.moveTo((377.779000, 133.210000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:06.500 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z20.0000 F6000 ; move to target 2024-04-02 13:19:06.501 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:06.501 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z20.0000 F6000 ; move to target 2024-04-02 13:19:06.503 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:06.817 AbstractHeadMountable DEBUG: N2.moveTo((377.779000, 133.210000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:06.819 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z10.0000 F6000 ; move to target 2024-04-02 13:19:06.819 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:06.819 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z10.0000 F6000 ; move to target 2024-04-02 13:19:06.820 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:07.043 AbstractHeadMountable DEBUG: N2.moveTo((377.779000, 133.210000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:07.045 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:19:07.045 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 Z0.0000 F6000 ; move to target 2024-04-02 13:19:07.045 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:07.046 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:11.327 AbstractHeadMountable DEBUG: N3.moveTo((348.579000, 108.310000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:11.329 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:19:11.329 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:11.329 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:19:11.330 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:11.832 AbstractHeadMountable DEBUG: N3.moveTo((348.579000, 108.310000, 20.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:11.834 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A20.0000 F6000 ; move to target 2024-04-02 13:19:11.835 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A20.0000 F6000 ; move to target 2024-04-02 13:19:11.835 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:11.836 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:17.019 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, -10.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:17.021 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:19:17.022 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:17.022 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A10.0000 F6000 ; move to target 2024-04-02 13:19:17.022 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:17.535 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:17.537 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:19:17.537 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:17.537 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:19:17.538 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:17.825 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, 10.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:17.826 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:19:17.827 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:17.827 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A-10.0000 F6000 ; move to target 2024-04-02 13:19:17.829 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:19.877 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, 0.000000, 0.000000 mm), 0.86) 2024-04-02 13:19:19.878 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:19:19.879 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:19.879 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1716 G1 A0.0000 F6000 ; move to target 2024-04-02 13:19:19.880 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:22.354 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:19:22.356 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S6656 G1 C-10.00 F10946 ; move to target 2024-04-02 13:19:22.356 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S6656 G1 C-10.00 F10946 ; move to target 2024-04-02 13:19:22.356 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:22.357 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:27.553 AbstractHeadMountable DEBUG: N4.moveTo((388.279000, 108.110000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:19:27.555 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X320.0000 F6320 ; move to target 2024-04-02 13:19:27.556 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X320.0000 F6320 ; move to target 2024-04-02 13:19:27.556 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:27.557 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:35.281 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:19:35.283 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S2219 G1 X310.0000 F6320 ; move to target 2024-04-02 13:19:35.283 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S2219 G1 X310.0000 F6320 ; move to target 2024-04-02 13:19:35.283 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:35.285 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:36.043 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 118.110000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:19:36.046 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y120.1265 F6000 ; move to target 2024-04-02 13:19:36.047 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y120.0000 F6000 ; move to target 2024-04-02 13:19:36.047 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y120.1265 F6000 ; move to target 2024-04-02 13:19:36.047 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y120.0000 F6000 ; move to target 2024-04-02 13:19:36.047 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:36.049 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:36.050 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:36.737 AbstractHeadMountable DEBUG: N4.moveTo((378.279000, 108.110000, 0.000000, -10.000000 mm), 0.86) 2024-04-02 13:19:36.742 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:19:36.742 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S1849 G1 Y110.1265 F6000 ; move to target 2024-04-02 13:19:36.742 GcodeAsyncDriver DEBUG: [Smoothie:COM4] commandQueue offer >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:19:36.743 GcodeAsyncDriver$WriterThread TRACE: [Smoothie:COM4] >> M204 S156 G1 Y110.0000 F6000 ; move to target 2024-04-02 13:19:36.743 AbstractMachine TRACE: Machine entering idle state. 2024-04-02 13:19:36.744 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok 2024-04-02 13:19:36.745 GcodeDriver$ReaderThread TRACE: [Smoothie:COM4] << ok