G29_Repeat_Fail

G29_repeat.txt

17:54:44.810 : echo:DEBUG:LEVELING
17:54:46.890 : N30 M105 *52
17:54:47.498 : N31 G29 *0
17:54:47.498 : gcode_G29 >>>
17:54:47.506 : > current_position: (0.00, 0.00, 10.75)
17:54:47.506 : G29 Auto Bed Leveling
17:54:47.510 : BEFORE matrix.set_to_identity > corrected_position: (0.00, 0.00, 10.75)
17:54:47.510 : BEFORE matrix.set_to_identity > current_position: (0.00, 0.00, 10.75)
17:54:47.514 : AFTER matrix.set_to_identity > uncorrected_position: (0.00, 0.00, 10.75)
17:54:47.518 : sync_plan_position > current_position: (0.00, 0.00, 10.75)
17:54:47.518 : setup_for_endstop_move > enable_endstops(true)
17:54:47.518 : z_before = (before) 10.00
17:54:47.522 : probe_pt >>>
17:54:47.522 : > ProbeAction:1
17:54:47.522 : > current_position: (0.00, 0.00, 10.75)
17:54:47.522 : Z Raise to z_before 10.00
17:54:47.526 : > do_blocking_move_to_z 10.00
17:54:47.526 : do_blocking_move_to: (0.00, 0.00, 10.00)
17:54:47.734 : > do_blocking_move_to_xy 140.00, 10.00
17:54:47.734 : do_blocking_move_to: (140.00, 10.00, 10.00)
17:54:48.810 : > ProbeDeploy
17:54:48.814 : deploy_z_probe > current_position: (140.00, 10.00, 10.00)
17:54:49.498 : echo:busy: processing
17:54:49.950 : N32 M105 *54
17:54:51.502 : echo:busy: processing
17:54:53.014 : N33 M105 *55
17:54:53.502 : echo:busy: processing
17:54:53.914 : sync_plan_position > current_position: (140.00, 10.00, -0.25)
17:54:53.918 : run_z_probe > current_position: (140.00, 10.00, -0.25)
17:54:53.918 : <<< probe_pt
17:54:53.918 : z_before = (between) 4.75
17:54:53.918 : probe_pt >>>
17:54:53.922 : > ProbeAction:0
17:54:53.926 : > current_position: (140.00, 10.00, -0.25)
17:54:53.926 : Z Raise to z_before 4.75
17:54:53.926 : > do_blocking_move_to_z 4.75
17:54:53.926 : do_blocking_move_to: (140.00, 10.00, 4.75)
17:54:55.186 : > do_blocking_move_to_xy 10.00, 10.00
17:54:55.186 : do_blocking_move_to: (10.00, 10.00, 4.75)
17:54:55.506 : echo:busy: processing
17:54:56.078 : N34 M105 *48
17:54:57.502 : echo:busy: processing
17:54:59.143 : N35 M105 *49
17:54:59.507 : echo:busy: processing
17:54:59.735 : sync_plan_position > current_position: (10.00, 10.00, 0.67)
17:54:59.739 : run_z_probe > current_position: (10.00, 10.00, 0.67)
17:54:59.739 : <<< probe_pt
17:54:59.739 : z_before = (between) 5.67
17:54:59.739 : probe_pt >>>
17:54:59.743 : > ProbeAction:0
17:54:59.743 : > current_position: (10.00, 10.00, 0.67)
17:54:59.743 : Z Raise to z_before 5.67
17:54:59.747 : > do_blocking_move_to_z 5.67
17:54:59.747 : do_blocking_move_to: (10.00, 10.00, 5.67)
17:55:01.003 : > do_blocking_move_to_xy 10.00, 130.00
17:55:01.003 : do_blocking_move_to: (10.00, 130.00, 5.67)
17:55:01.507 : echo:busy: processing
17:55:02.207 : N36 M105 *50
17:55:03.511 : echo:busy: processing
17:55:05.263 : N37 M105 *51
17:55:05.511 : echo:busy: processing
17:55:05.743 : sync_plan_position > current_position: (10.00, 130.00, 0.81)
17:55:05.743 : run_z_probe > current_position: (10.00, 130.00, 0.81)
17:55:05.743 : <<< probe_pt
17:55:05.747 : z_before = (between) 5.81
17:55:05.747 : probe_pt >>>
17:55:05.747 : > ProbeAction:2
17:55:05.747 : > current_position: (10.00, 130.00, 0.81)
17:55:05.751 : Z Raise to z_before 5.81
17:55:05.751 : > do_blocking_move_to_z 5.81
17:55:05.755 : do_blocking_move_to: (10.00, 130.00, 5.81)
17:55:07.047 : > do_blocking_move_to_xy 140.00, 130.00
17:55:07.051 : do_blocking_move_to: (140.00, 130.00, 5.81)
17:55:07.511 : echo:busy: processing
17:55:08.327 : N38 M105 *60
17:55:09.511 : echo:busy: processing
17:55:11.391 : N39 M105 *61
17:55:11.511 : echo:busy: processing
17:55:12.155 : sync_plan_position > current_position: (140.00, 130.00, -0.25)
17:55:12.159 : run_z_probe > current_position: (140.00, 130.00, -0.25)
17:55:12.159 : > ProbeStow (stow_z_probe will do Z Raise)
17:55:12.163 : stow_z_probe > current_position: (140.00, 130.00, -0.25)
17:55:12.163 : Raise Z (after) by 10
17:55:12.167 : > SERVO_ENDSTOPS > raise_z_after_probing()
17:55:12.167 : do_blocking_move_to: (140.00, 130.00, 9.75)
17:55:13.515 : echo:busy: processing
17:55:14.675 : <<< probe_pt
17:55:14.679 : > probing complete > current_position: (140.00, 130.00, 9.75)
17:55:14.683 : clean_up_after_endstop_move > ENDSTOPS_ONLY_FOR_HOMING > endstops_not_homing()
17:55:14.691 : Eqn coefficients: a: -0.00762740 b: 0.00054687 d: 0.84817323
17:55:14.695 : >>> set_bed_level_equation_lsq > uncorrected_position: (140.00, 130.00, 9.75)
17:55:14.699 : >>> set_bed_level_equation_lsq > current_position: (140.00, 130.00, 9.75)
17:55:14.703 : <<< set_bed_level_equation_lsq > corrected_position: (139.92, 130.01, 10.74)
17:55:14.707 : sync_plan_position > current_position: (139.92, 130.01, 10.74)
17:55:14.707 : 
17:55:14.707 : Bed Level Correction Matrix:
17:55:14.707 : +0.999971 +0.000000 -0.007627
17:55:14.711 : +0.000004 +1.000000 +0.000547
17:55:14.711 : +0.007627 -0.000547 +0.999971
17:55:14.715 : > BEFORE apply_rotation_xyz > z_tmp  = 10.74
17:55:14.715 : > BEFORE apply_rotation_xyz > real_z = 9.75
17:55:14.719 : > AFTER apply_rotation_xyz > z_tmp  = 9.67
17:55:14.719 : sync_plan_position > current_position: (139.92, 130.01, 10.68)
17:55:14.723 : > corrected Z in G29 > current_position: (139.92, 130.01, 10.68)
17:55:14.723 : <<< gcode_G29
17:55:14.727 : X:139.92 Y:130.01 Z:10.68 E:0.00 Count X: 22400 Y:20800 Z:30985
17:55:14.727 : N40 M105 *51
17:55:17.271 : N41 G91 *4
17:55:17.271 : N42 G1 Z10 F120 *16
17:55:17.271 : N43 G90 *7
17:55:17.515 : N44 M105 *55
17:55:20.571 : N45 M105 *54
17:55:23.635 : N46 M105 *53
17:55:25.675 : N47 G29 *1
17:55:25.675 : gcode_G29 >>>
17:55:25.679 : > current_position: (139.92, 130.01, 20.68)
17:55:25.679 : G29 Auto Bed Leveling
17:55:25.687 : BEFORE matrix.set_to_identity > corrected_position: (140.08, 129.99, 19.68)
17:55:25.691 : BEFORE matrix.set_to_identity > current_position: (139.92, 130.01, 20.68)
17:55:25.695 : AFTER matrix.set_to_identity > uncorrected_position: (140.08, 129.99, 19.68)
17:55:25.695 : sync_plan_position > current_position: (140.08, 129.99, 19.68)
17:55:25.699 : setup_for_endstop_move > enable_endstops(true)
17:55:25.699 : z_before = (before) 10.00
17:55:25.699 : probe_pt >>>
17:55:25.699 : > ProbeAction:1
17:55:25.703 : > current_position: (140.08, 129.99, 19.68)
17:55:25.703 : Z Raise to z_before 10.00
17:55:25.707 : > do_blocking_move_to_z 10.00
17:55:25.707 : do_blocking_move_to: (140.08, 129.99, 10.00)
17:55:26.719 : N48 M105 *59
17:55:27.675 : echo:busy: processing
17:55:28.135 : > do_blocking_move_to_xy 140.00, 10.00
17:55:28.139 : do_blocking_move_to: (140.00, 10.00, 10.00)
17:55:29.055 : > ProbeDeploy
17:55:29.059 : deploy_z_probe > current_position: (140.00, 10.00, 10.00)
17:55:29.679 : echo:busy: processing
17:55:29.783 : N49 M105 *58
17:55:31.679 : echo:busy: processing
17:55:32.847 : N50 M105 *50
17:55:33.684 : echo:busy: processing
17:55:34.148 : sync_plan_position > current_position: (140.00, 10.00, -0.24)
17:55:34.152 : run_z_probe > current_position: (140.00, 10.00, -0.24)
17:55:34.152 : <<< probe_pt
17:55:34.152 : z_before = (between) 4.76
17:55:34.156 : probe_pt >>>
17:55:34.156 : > ProbeAction:0
17:55:34.156 : > current_position: (140.00, 10.00, -0.24)
17:55:34.156 : Z Raise to z_before 4.76
17:55:34.160 : > do_blocking_move_to_z 4.76
17:55:34.160 : do_blocking_move_to: (140.00, 10.00, 4.76)
17:55:35.420 : > do_blocking_move_to_xy 10.00, 10.00
17:55:35.420 : do_blocking_move_to: (10.00, 10.00, 4.76)
17:55:35.680 : echo:busy: processing
17:55:35.904 : N51 M105 *51
17:55:37.684 : echo:busy: processing
17:55:38.968 : N52 M105 *48
17:55:39.684 : echo:busy: processing
17:55:39.968 : sync_plan_position > current_position: (10.00, 10.00, 0.68)
17:55:39.972 : run_z_probe > current_position: (10.00, 10.00, 0.68)
17:55:39.972 : <<< probe_pt
17:55:39.972 : z_before = (between) 5.68
17:55:39.972 : probe_pt >>>
17:55:39.976 : > ProbeAction:0
17:55:39.976 : > current_position: (10.00, 10.00, 0.68)
17:55:39.976 : Z Raise to z_before 5.68
17:55:39.980 : > do_blocking_move_to_z 5.68
17:55:39.980 : do_blocking_move_to: (10.00, 10.00, 5.68)
17:55:41.236 : > do_blocking_move_to_xy 10.00, 130.00
17:55:41.236 : do_blocking_move_to: (10.00, 130.00, 5.68)
17:55:41.688 : echo:busy: processing
17:55:42.032 : N53 M105 *49
17:55:43.688 : echo:busy: processing
17:55:45.096 : N54 M105 *54
17:55:45.688 : echo:busy: processing
17:55:45.984 : sync_plan_position > current_position: (10.00, 130.00, 0.73)
17:55:45.988 : run_z_probe > current_position: (10.00, 130.00, 0.73)
17:55:45.988 : <<< probe_pt
17:55:45.988 : z_before = (between) 5.73
17:55:45.992 : probe_pt >>>
17:55:45.992 : > ProbeAction:2
17:55:45.992 : > current_position: (10.00, 130.00, 0.73)
17:55:45.992 : Z Raise to z_before 5.73
17:55:45.996 : > do_blocking_move_to_z 5.73
17:55:45.996 : do_blocking_move_to: (10.00, 130.00, 5.73)
17:55:47.256 : > do_blocking_move_to_xy 140.00, 130.00
17:55:47.260 : do_blocking_move_to: (140.00, 130.00, 5.73)
17:55:47.688 : echo:busy: processing
17:55:48.160 : N55 M105 *55
17:55:49.692 : echo:busy: processing
17:55:51.692 : echo:busy: processing
17:55:52.312 : sync_plan_position > current_position: (140.00, 130.00, -0.37)
17:55:52.320 : run_z_probe > current_position: (140.00, 130.00, -0.37)
17:55:52.320 : > ProbeStow (stow_z_probe will do Z Raise)
17:55:52.324 : stow_z_probe > current_position: (140.00, 130.00, -0.37)
17:55:52.324 : Raise Z (after) by 10
17:55:52.324 : > SERVO_ENDSTOPS > raise_z_after_probing()
17:55:52.324 : do_blocking_move_to: (140.00, 130.00, 9.63)
17:55:53.692 : echo:busy: processing
17:55:54.832 : <<< probe_pt
17:55:54.840 : > probing complete > current_position: (140.00, 130.00, 9.63)
17:55:54.840 : clean_up_after_endstop_move > ENDSTOPS_ONLY_FOR_HOMING > endstops_not_homing()
17:55:54.852 : Eqn coefficients: a: -0.00773077 b: -0.00032292 d: 0.88451156
17:55:54.856 : >>> set_bed_level_equation_lsq > uncorrected_position: (140.00, 130.00, 9.63)
17:55:54.856 : >>> set_bed_level_equation_lsq > current_position: (140.00, 130.00, 9.63)
17:55:54.864 : <<< set_bed_level_equation_lsq > corrected_position: (139.92, 130.00, 10.76)
17:55:54.864 : sync_plan_position > current_position: (139.92, 130.00, 10.76)
17:55:54.864 : 
17:55:54.868 : Bed Level Correction Matrix:
17:55:54.868 : +0.999970 +0.000000 -0.007731
17:55:54.872 : -0.000002 +1.000000 -0.000323
17:55:54.872 : +0.007731 +0.000323 +0.999970
17:55:54.872 : > BEFORE apply_rotation_xyz > z_tmp  = 10.76
17:55:54.876 : > BEFORE apply_rotation_xyz > real_z = 9.63
17:55:54.876 : > AFTER apply_rotation_xyz > z_tmp  = 9.55
17:55:54.880 : sync_plan_position > current_position: (139.92, 130.00, 10.67)
17:55:54.884 : > corrected Z in G29 > current_position: (139.92, 130.00, 10.67)
17:55:54.884 : <<< gcode_G29
17:55:54.888 : X:139.92 Y:130.00 Z:10.67 E:0.00 Count X: 22400 Y:20800 Z:30546
17:55:54.888 : N56 M105 *52
17:55:57.340 : N57 M105 *53
17:55:58.248 : N58 G28 Z0 *68
17:55:58.248 : gcode_G28 >>>
17:55:58.256 : setup_for_endstop_move > enable_endstops(true)
17:55:58.256 : > Z_SAFE_HOMING >>>
17:55:58.256 : >>> homeaxis(2)
17:55:58.256 : sync_plan_position > current_position: (139.92, 130.00, 0.00)
17:55:58.256 : deploy_z_probe > current_position: (139.92, 130.00, 0.00)
17:55:58.328 : sync_plan_position > current_position: (139.92, 130.00, 0.00)
17:55:58.332 : > enable_endstops(false)
17:55:58.836 : > enable_endstops(true)
17:56:00.252 : echo:busy: processing
17:56:00.408 : N59 M105 *59
17:56:02.252 : echo:busy: processing
17:56:03.472 : N60 M105 *49
17:56:04.256 : echo:busy: processing
17:56:04.860 : > TRIGGER ENDSTOP > current_position: (139.92, 130.00, 0.00)
17:56:04.860 : set_axis_is_at_home(2) >>>
17:56:04.860 : > zprobe_zoffset==-0.75
17:56:04.876 : > home_offset[axis]==0.00 > current_position: (139.92, 130.00, 0.75)
17:56:04.876 : <<< set_axis_is_at_home(2)
17:56:04.876 : sync_plan_position > current_position: (139.92, 130.00, 0.75)
17:56:04.876 : > AFTER set_axis_is_at_home > current_position: (139.92, 130.00, 0.75)
17:56:04.876 : > SERVO_LEVELING > (stow_z_probe())
17:56:04.880 : stow_z_probe > current_position: (139.92, 130.00, 0.75)
17:56:04.880 : Raise Z (after) by 10
17:56:04.888 : > SERVO_ENDSTOPS > raise_z_after_probing()
17:56:04.888 : do_blocking_move_to: (139.92, 130.00, 10.75)
17:56:06.256 : echo:busy: processing
17:56:06.528 : N61 M105 *48
17:56:07.392 : <<< homeaxis(2)
17:56:07.392 : <<< Z_SAFE_HOMING
17:56:07.396 : > (home_all_axis || homeZ) > final > current_position: (139.92, 130.00, 10.75)
17:56:07.396 : sync_plan_position > current_position: (139.92, 130.00, 10.75)
17:56:07.400 : ENDSTOPS_ONLY_FOR_HOMING enable_endstops(false)
17:56:07.400 : <<< gcode_G28
17:56:07.404 : X:139.92 Y:130.00 Z:10.75 E:0.00 Count X: 22387 Y:20799 Z:34400