g29 thinkyhead debuggin

g29_test

===================
Offset X-40 Y0 Z-0.2
Inductive Sensor
===================

23:29:44.895 : echo:Marlin1.0.3 dev
23:29:44.898 : echo: Last Updated: Apr  8 2015 23:27:18 | Author: (none, default config)
23:29:44.899 : Compiled: Apr  8 2015
23:29:44.902 : echo: Free Memory: 89172  PlannerBufferBytes: 1344
23:29:45.042 : echo:V19 stored settings retrieved (392 bytes)
23:29:45.042 : echo:Steps per unit:
23:29:45.046 : echo:  M92 X320.00 Y320.00 Z12800.00 E550.00
23:29:45.046 : echo:Maximum feedrates (mm/s):
23:29:45.046 : echo:  M203 X500.00 Y500.00 Z4.00 E150.00
23:29:45.050 : echo:Maximum Acceleration (mm/s2):
23:29:45.050 : echo:  M201 X3000 Y3000 Z100 E8000
23:29:45.054 : echo:Accelerations: P=printing, R=retract and T=travel
23:29:45.055 : echo:  M204 P1000.00 R5000.00 T2000.00
23:29:45.063 : echo:Advanced variables: S=Min feedrate (mm/s), T=Min travel feedrate (mm/s), B=minimum segment time (ms), X=maximum XY jerk (mm/s),  Z=maximum Z jerk (mm/s),  E=maximum E jerk (mm/s)
23:29:45.063 : echo:  M205 S0.00 T0.00 B20000 X20.00 Z0.40 E5.00
23:29:45.063 : echo:Home offset (mm):
23:29:45.066 : echo:  M206 X0.00 Y0.00 Z0.00
23:29:45.066 : echo:PID settings:
23:29:45.066 : echo:
23:29:45.067 : M301 P17.99 I2.08 D38.85
23:29:45.070 : M304 P218.62 I20.78 D574.91
23:29:45.070 : echo:Filament settings: Disabled
23:29:45.070 : echo:Z-Probe Offset (mm):
23:29:45.071 : echo:  M851 Z-0.18
23:29:45.074 : First start for temperature finished.
23:29:45.388 : N1 M110 *2
23:29:46.875 : N1 M110 *2
23:29:48.408 : echo:SD init fail
23:29:48.409 : N2 M115 *4
23:29:48.430 : echo:Unknown command: ""
23:29:48.438 : FIRMWARE_NAME:Marlin V1; Sprinter/grbl mashup for gen6 FIRMWARE_URL:https://github.com/MarlinFirmware/Marlin PROTOCOL_VERSION:1.0 MACHINE_TYPE:Marlin4Due EXTRUDER_COUNT:1 UUID:00000000-0000-0000-0000-000000000000
23:29:48.438 : N4 M111 S6 *67
23:29:48.487 : N5 T0 *31
23:29:48.490 : echo:Active Extruder: 0
23:29:48.490 : N6 M20 *55
23:29:48.494 : Begin file list
23:29:48.496 : End file list
23:29:48.497 : N7 M80 *60
23:29:48.498 : N8 M220 S100 *73
23:29:48.502 : N9 M221 S100 *73
23:29:48.506 : N10 M111 S6 *118
23:29:48.511 : N11 T0 *42
23:29:48.515 : echo:Active Extruder: 0
23:29:50.141 : N14 G1 Z3 *116
23:29:52.285 : N17 G28 *5
23:29:52.287 : gcode_G28 >>>
23:29:52.734 : home_axis with axis=0
23:29:52.734 : echo:home_axis > current_position: (220.00, 0.00, 3.00)
23:29:52.738 : echo:> homeX: (220.00, 0.00, 3.00)
23:29:53.287 : home_axis with axis=1
23:29:53.291 : echo:home_axis > current_position: (220.00, 215.00, 3.00)
23:29:53.291 : echo:> homeY: (220.00, 215.00, 3.00)
23:29:53.291 : > Z_SAFE_HOMING >>>
23:29:53.295 : echo:> home_all_axis > destination: (150.00, 107.00, 3.00)
23:29:57.141 : home_axis with axis=2
23:29:57.146 : echo:home_axis > current_position: (150.00, 107.00, 0.00)
23:29:57.146 : echo:> home_all_axis > final: (150.00, 107.00, 0.00)
23:29:57.147 : <<< Z_SAFE_HOMING
23:29:57.150 : ENDSTOPS_ONLY_FOR_HOMING enable_endstops(false)
23:29:57.150 : <<< gcode_G28
23:30:02.645 : N24 G29 P4 V4 *2
23:30:02.647 : gcode_G29 >>>
23:30:02.651 : G29 Auto Bed Leveling
23:30:02.651 : enable_endstops(true)
23:30:02.652 : probe_pt >>>
23:30:02.652 : > ProbeAction:1
23:30:02.655 : echo:> current_position: (150.00, 107.00, 0.18)
23:30:02.655 : echo:> do_blocking_move_to: (150.00, 107.00, 3.00)
23:30:02.659 : echo:do_blocking_move_to: (150.00, 107.00, 3.00)
23:30:03.401 : echo:> do_blocking_move_to: (80.00, 45.00, 3.00)
23:30:03.402 : echo:do_blocking_move_to: (80.00, 45.00, 3.00)
23:30:04.191 : > ProbeDeploy
23:30:04.192 : echo:deploy_z_probe > current_position: (80.00, 45.00, 3.00)
23:30:06.231 : echo:run_z_probe > current_position: (80.00, 45.00, 0.25)
23:30:06.235 : Bed X: 40.000 Y: 45.000 Z: 0.249
23:30:06.235 : probe_pt >>>
23:30:06.235 : > ProbeAction:0
23:30:06.235 : echo:> current_position: (80.00, 45.00, 0.25)
23:30:06.239 : echo:> do_blocking_move_to: (80.00, 45.00, 1.25)
23:30:06.239 : echo:do_blocking_move_to: (80.00, 45.00, 1.25)
23:30:06.526 : echo:> do_blocking_move_to: (125.00, 45.00, 1.25)
23:30:06.530 : echo:do_blocking_move_to: (125.00, 45.00, 1.25)
23:30:08.559 : echo:run_z_probe > current_position: (125.00, 45.00, 0.26)
23:30:08.559 : Raise Z by 1
23:30:08.564 : echo:> ProbeStay > do_blocking_move_to: (125.00, 45.00, 1.26)
23:30:08.564 : echo:do_blocking_move_to: (125.00, 45.00, 1.26)
23:30:08.849 : Bed X: 85.000 Y: 45.000 Z: 0.256
23:30:08.849 : probe_pt >>>
23:30:08.849 : > ProbeAction:0
23:30:08.853 : echo:> current_position: (125.00, 45.00, 1.26)
23:30:08.853 : echo:> do_blocking_move_to: (125.00, 45.00, 2.26)
23:30:08.856 : echo:do_blocking_move_to: (125.00, 45.00, 2.26)
23:30:09.143 : echo:> do_blocking_move_to: (170.00, 45.00, 2.26)
23:30:09.143 : echo:do_blocking_move_to: (170.00, 45.00, 2.26)
23:30:11.433 : echo:run_z_probe > current_position: (170.00, 45.00, 0.28)
23:30:11.433 : Raise Z by 1
23:30:11.437 : echo:> ProbeStay > do_blocking_move_to: (170.00, 45.00, 1.28)
23:30:11.437 : echo:do_blocking_move_to: (170.00, 45.00, 1.28)
23:30:11.724 : Bed X: 130.000 Y: 45.000 Z: 0.283
23:30:11.724 : probe_pt >>>
23:30:11.724 : > ProbeAction:0
23:30:11.728 : echo:> current_position: (170.00, 45.00, 1.28)
23:30:11.728 : echo:> do_blocking_move_to: (170.00, 45.00, 2.28)
23:30:11.732 : echo:do_blocking_move_to: (170.00, 45.00, 2.28)
23:30:12.019 : echo:> do_blocking_move_to: (215.00, 45.00, 2.28)
23:30:12.019 : echo:do_blocking_move_to: (215.00, 45.00, 2.28)
23:30:14.289 : echo:run_z_probe > current_position: (215.00, 45.00, 0.33)
23:30:14.289 : Raise Z by 1
23:30:14.293 : echo:> ProbeStay > do_blocking_move_to: (215.00, 45.00, 1.33)
23:30:14.293 : echo:do_blocking_move_to: (215.00, 45.00, 1.33)
23:30:14.579 : Bed X: 175.000 Y: 45.000 Z: 0.332
23:30:14.579 : probe_pt >>>
23:30:14.579 : > ProbeAction:0
23:30:14.583 : echo:> current_position: (215.00, 45.00, 1.33)
23:30:14.583 : echo:> do_blocking_move_to: (215.00, 45.00, 2.33)
23:30:14.587 : echo:do_blocking_move_to: (215.00, 45.00, 2.33)
23:30:14.874 : echo:> do_blocking_move_to: (80.00, 98.00, 2.33)
23:30:14.874 : echo:do_blocking_move_to: (80.00, 98.00, 2.33)
23:30:17.930 : echo:run_z_probe > current_position: (80.00, 98.00, 0.21)
23:30:17.930 : Raise Z by 1
23:30:17.934 : echo:> ProbeStay > do_blocking_move_to: (80.00, 98.00, 1.21)
23:30:17.934 : echo:do_blocking_move_to: (80.00, 98.00, 1.21)
23:30:18.222 : Bed X: 40.000 Y: 98.000 Z: 0.205
23:30:18.222 : probe_pt >>>
23:30:18.226 : > ProbeAction:0
23:30:18.226 : echo:> current_position: (80.00, 98.00, 1.21)
23:30:18.226 : echo:> do_blocking_move_to: (80.00, 98.00, 2.21)
23:30:18.230 : echo:do_blocking_move_to: (80.00, 98.00, 2.21)
23:30:18.517 : echo:> do_blocking_move_to: (125.00, 98.00, 2.21)
23:30:18.517 : echo:do_blocking_move_to: (125.00, 98.00, 2.21)
23:30:20.802 : echo:run_z_probe > current_position: (125.00, 98.00, 0.18)
23:30:20.803 : Raise Z by 1
23:30:20.806 : echo:> ProbeStay > do_blocking_move_to: (125.00, 98.00, 1.18)
23:30:20.806 : echo:do_blocking_move_to: (125.00, 98.00, 1.18)
23:30:21.092 : Bed X: 85.000 Y: 98.000 Z: 0.184
23:30:21.093 : probe_pt >>>
23:30:21.093 : > ProbeAction:0
23:30:21.096 : echo:> current_position: (125.00, 98.00, 1.18)
23:30:21.096 : echo:> do_blocking_move_to: (125.00, 98.00, 2.18)
23:30:21.100 : echo:do_blocking_move_to: (125.00, 98.00, 2.18)
23:30:21.387 : echo:> do_blocking_move_to: (170.00, 98.00, 2.18)
23:30:21.387 : echo:do_blocking_move_to: (170.00, 98.00, 2.18)
23:30:23.685 : echo:run_z_probe > current_position: (170.00, 98.00, 0.19)
23:30:23.685 : Raise Z by 1
23:30:23.686 : echo:> ProbeStay > do_blocking_move_to: (170.00, 98.00, 1.19)
23:30:23.689 : echo:do_blocking_move_to: (170.00, 98.00, 1.19)
23:30:23.976 : Bed X: 130.000 Y: 98.000 Z: 0.190
23:30:23.976 : probe_pt >>>
23:30:23.976 : > ProbeAction:0
23:30:23.977 : echo:> current_position: (170.00, 98.00, 1.19)
23:30:23.980 : echo:> do_blocking_move_to: (170.00, 98.00, 2.19)
23:30:23.984 : echo:do_blocking_move_to: (170.00, 98.00, 2.19)
23:30:24.267 : echo:> do_blocking_move_to: (215.00, 98.00, 2.19)
23:30:24.271 : echo:do_blocking_move_to: (215.00, 98.00, 2.19)
23:30:26.540 : echo:run_z_probe > current_position: (215.00, 98.00, 0.22)
23:30:26.540 : Raise Z by 1
23:30:26.540 : echo:> ProbeStay > do_blocking_move_to: (215.00, 98.00, 1.22)
23:30:26.544 : echo:do_blocking_move_to: (215.00, 98.00, 1.22)
23:30:26.831 : Bed X: 175.000 Y: 98.000 Z: 0.218
23:30:26.831 : probe_pt >>>
23:30:26.831 : > ProbeAction:0
23:30:26.831 : echo:> current_position: (215.00, 98.00, 1.22)
23:30:26.835 : echo:> do_blocking_move_to: (215.00, 98.00, 2.22)
23:30:26.835 : echo:do_blocking_move_to: (215.00, 98.00, 2.22)
23:30:27.122 : echo:> do_blocking_move_to: (80.00, 151.00, 2.22)
23:30:27.126 : echo:do_blocking_move_to: (80.00, 151.00, 2.22)
23:30:30.169 : echo:run_z_probe > current_position: (80.00, 151.00, 0.15)
23:30:30.169 : Raise Z by 1
23:30:30.170 : echo:> ProbeStay > do_blocking_move_to: (80.00, 151.00, 1.15)
23:30:30.173 : echo:do_blocking_move_to: (80.00, 151.00, 1.15)
23:30:30.461 : Bed X: 40.000 Y: 151.000 Z: 0.146
23:30:30.461 : probe_pt >>>
23:30:30.461 : > ProbeAction:0
23:30:30.464 : echo:> current_position: (80.00, 151.00, 1.15)
23:30:30.464 : echo:> do_blocking_move_to: (80.00, 151.00, 2.15)
23:30:30.465 : echo:do_blocking_move_to: (80.00, 151.00, 2.15)
23:30:30.751 : echo:> do_blocking_move_to: (125.00, 151.00, 2.15)
23:30:30.755 : echo:do_blocking_move_to: (125.00, 151.00, 2.15)
23:30:33.045 : echo:run_z_probe > current_position: (125.00, 151.00, 0.10)
23:30:33.045 : Raise Z by 1
23:30:33.049 : echo:> ProbeStay > do_blocking_move_to: (125.00, 151.00, 1.10)
23:30:33.049 : echo:do_blocking_move_to: (125.00, 151.00, 1.10)
23:30:33.336 : Bed X: 85.000 Y: 151.000 Z: 0.098
23:30:33.336 : probe_pt >>>
23:30:33.336 : > ProbeAction:0
23:30:33.340 : echo:> current_position: (125.00, 151.00, 1.10)
23:30:33.340 : echo:> do_blocking_move_to: (125.00, 151.00, 2.10)
23:30:33.344 : echo:do_blocking_move_to: (125.00, 151.00, 2.10)
23:30:33.647 : echo:> do_blocking_move_to: (170.00, 151.00, 2.10)
23:30:33.651 : echo:do_blocking_move_to: (170.00, 151.00, 2.10)
23:30:35.934 : echo:run_z_probe > current_position: (170.00, 151.00, 0.07)
23:30:35.938 : Raise Z by 1
23:30:35.938 : echo:> ProbeStay > do_blocking_move_to: (170.00, 151.00, 1.07)
23:30:35.942 : echo:do_blocking_move_to: (170.00, 151.00, 1.07)
23:30:36.229 : Bed X: 130.000 Y: 151.000 Z: 0.074
23:30:36.229 : probe_pt >>>
23:30:36.229 : > ProbeAction:0
23:30:36.229 : echo:> current_position: (170.00, 151.00, 1.07)
23:30:36.233 : echo:> do_blocking_move_to: (170.00, 151.00, 2.07)
23:30:36.233 : echo:do_blocking_move_to: (170.00, 151.00, 2.07)
23:30:36.520 : echo:> do_blocking_move_to: (215.00, 151.00, 2.07)
23:30:36.523 : echo:do_blocking_move_to: (215.00, 151.00, 2.07)
23:30:38.800 : echo:run_z_probe > current_position: (215.00, 151.00, 0.07)
23:30:38.801 : Raise Z by 1
23:30:38.801 : echo:> ProbeStay > do_blocking_move_to: (215.00, 151.00, 1.07)
23:30:38.804 : echo:do_blocking_move_to: (215.00, 151.00, 1.07)
23:30:39.091 : Bed X: 175.000 Y: 151.000 Z: 0.068
23:30:39.091 : probe_pt >>>
23:30:39.091 : > ProbeAction:0
23:30:39.092 : echo:> current_position: (215.00, 151.00, 1.07)
23:30:39.095 : echo:> do_blocking_move_to: (215.00, 151.00, 2.07)
23:30:39.099 : echo:do_blocking_move_to: (215.00, 151.00, 2.07)
23:30:39.386 : echo:> do_blocking_move_to: (80.00, 204.00, 2.07)
23:30:39.387 : echo:do_blocking_move_to: (80.00, 204.00, 2.07)
23:30:42.401 : echo:run_z_probe > current_position: (80.00, 204.00, 0.11)
23:30:42.405 : Raise Z by 1
23:30:42.405 : echo:> ProbeStay > do_blocking_move_to: (80.00, 204.00, 1.11)
23:30:42.409 : echo:do_blocking_move_to: (80.00, 204.00, 1.11)
23:30:42.691 : Bed X: 40.000 Y: 204.000 Z: 0.108
23:30:42.696 : probe_pt >>>
23:30:42.696 : > ProbeAction:0
23:30:42.696 : echo:> current_position: (80.00, 204.00, 1.11)
23:30:42.700 : echo:> do_blocking_move_to: (80.00, 204.00, 2.11)
23:30:42.700 : echo:do_blocking_move_to: (80.00, 204.00, 2.11)
23:30:42.987 : echo:> do_blocking_move_to: (125.00, 204.00, 2.11)
23:30:42.988 : echo:do_blocking_move_to: (125.00, 204.00, 2.11)
23:30:45.289 : echo:run_z_probe > current_position: (125.00, 204.00, 0.03)
23:30:45.289 : Raise Z by 1
23:30:45.293 : echo:> ProbeStay > do_blocking_move_to: (125.00, 204.00, 1.03)
23:30:45.293 : echo:do_blocking_move_to: (125.00, 204.00, 1.03)
23:30:45.580 : Bed X: 85.000 Y: 204.000 Z: 0.033
23:30:45.580 : probe_pt >>>
23:30:45.580 : > ProbeAction:0
23:30:45.584 : echo:> current_position: (125.00, 204.00, 1.03)
23:30:45.584 : echo:> do_blocking_move_to: (125.00, 204.00, 2.03)
23:30:45.587 : echo:do_blocking_move_to: (125.00, 204.00, 2.03)
23:30:45.874 : echo:> do_blocking_move_to: (170.00, 204.00, 2.03)
23:30:45.874 : echo:do_blocking_move_to: (170.00, 204.00, 2.03)
23:30:48.181 : echo:run_z_probe > current_position: (170.00, 204.00, -0.02)
23:30:48.181 : Raise Z by 1
23:30:48.185 : echo:> ProbeStay > do_blocking_move_to: (170.00, 204.00, 0.98)
23:30:48.186 : echo:do_blocking_move_to: (170.00, 204.00, 0.98)
23:30:48.471 : Bed X: 130.000 Y: 204.000 Z: -0.016
23:30:48.471 : probe_pt >>>
23:30:48.476 : > ProbeAction:2
23:30:48.476 : echo:> current_position: (170.00, 204.00, 0.98)
23:30:48.477 : echo:> do_blocking_move_to: (170.00, 204.00, 1.98)
23:30:48.479 : echo:do_blocking_move_to: (170.00, 204.00, 1.98)
23:30:48.766 : echo:> do_blocking_move_to: (215.00, 204.00, 1.98)
23:30:48.770 : echo:do_blocking_move_to: (215.00, 204.00, 1.98)
23:30:51.052 : echo:run_z_probe > current_position: (215.00, 204.00, -0.04)
23:30:51.052 : > ProbeStow
23:30:51.056 : echo:stow_z_probe > current_position: (215.00, 204.00, -0.04)
23:30:51.056 : Bed X: 175.000 Y: 204.000 Z: -0.039
23:30:51.060 : echo:> probing complete > current_position: (215.00, 204.00, -0.04)
23:30:51.060 : ENDSTOPS_ONLY_FOR_HOMING enable_endstops(false)
23:30:51.064 : Eqn coefficients: a: -0.00023850 b: -0.00165791 d: 0.38139874
23:30:51.064 : Mean of sampled points: 0.14935059
23:30:51.064 : 
23:30:51.065 : Bed Height Topography:
23:30:51.069 : +-----------+
23:30:51.069 : |...Back....|
23:30:51.069 : |Left..Right|
23:30:51.069 : |...Front...|
23:30:51.069 : +-----------+
23:30:51.073 : -0.04107 -0.11638 -0.16529 -0.18857
23:30:51.073 : -0.00357 -0.05169 -0.07521 -0.08169
23:30:51.073 : +0.05612 +0.03479 +0.04096 +0.06901
23:30:51.077 : +0.10010 +0.10627 +0.13346 +0.18276
23:30:51.077 : planeNormal x: 0.000238 y: 0.001658 z: 1.000000
23:30:51.081 : echo:set_bed_level_equation_lsq > current_position: (215.00, 204.00, 0.35)
23:30:51.081 : 
23:30:51.081 : Bed Level Correction Matrix:
23:30:51.082 : +1.000000 +0.000000 -0.000238
23:30:51.085 : -0.000000 +0.999999 -0.001658
23:30:51.085 : +0.000238 +0.001658 +0.999999
23:30:51.085 : <<< gcode_G29