search

prusa3d / Prusa-Firmware

Firmware for Original Prusa i3 3D printer by PrusaResearch
GNU General Public License v3.0
2.01k stars 1.05k forks source link

[BUG]<Tune/change filament from LCD-menu causes misalignment> #4498

Closed FrHePo closed 9 months ago

FrHePo commented 10 months ago

Printer type: MK3S Printer firmware version: 3.13.2 print via SD card

When changing filament during printing with the LCD-function <Tune/change filament> the print head doesn't return to the correct position creating a Y-misalignment of about +0.7mm. After I noticed this I did a cube test print (see picture) which confirmed me that this misalignment is independent of the printed object or the G-code. P1060830 P1060828

cozmo14047 commented 10 months ago

I have this but it's not as bad.

3d-gussner commented 9 months ago

@FrHePo @cozmo14047 Thanks for the bug report. I added few extra code lines to v3.13.2 to trouble shoot this and can't find any layer shift during the M600 color change.

Below part of the serial output:

As line 3 and line 6 are identical there should be no layer shift caused by the printer firmware.

1 2023-11-15 11:59:38,077 - Recv: X:139.88 Y:117.50 Z:0.20 E:5.16 Count X: 118.86 Y:120.25 Z:0.29 E:2.39
2 2023-11-15 11:59:38,083 - Recv: X:139.88 Y:117.50 Z:0.20 E:5.16 Count X: 118.66 Y:120.25 Z:0.29 E:2.39
3 2023-11-15 11:59:41,138 - Recv: X:110.32 Y:118.44 Z:0.20 E:6.09 Count X: 110.32 Y:118.44 Z:0.29 E:6.09
4 2023-11-15 11:59:58,225 - Recv: X:211.00 Y:0.00 Z:27.20 E:24.09 Count X: 211.00 Y:0.00 Z:27.47 E:19.11
5 2023-11-15 12:00:00,964 - Recv: X:211.00 Y:0.00 Z:27.20 E:24.09 Count X: 110.32 Y:118.44 Z:27.29 E:24.09
6 2023-11-15 12:00:02,088 - Recv: X:110.32 Y:118.44 Z:0.20 E:6.09 Count X: 110.32 Y:118.44 Z:0.29 E:6.09
7 2023-11-15 12:00:02,095 - Recv: X:110.32 Y:118.44 Z:0.20 E:6.09 Count X: 110.32 Y:118.44 Z:0.29 E:6.09

Please try to enable the position auto report with M155 S1 C4 or M155 S1 C5 for temperatures and positions.

Tested with MK404 and here a screenshot of a one layer print with a Tune -> Color change image

FrHePo commented 9 months ago

I have to add that I'am printing this cube with 0.25 nozzle which is correctly set in the hardware settings. P1060836

[INFO] RECV: T:215.1 /215.0 B:60.1 /60.0 T0:215.1 /215.0 @:50 B@:22 P:0.0 A:39.4 [INFO] RECV: X:129.87 Y:100.13 Z:2.45 E:2.57 Count X: 121.78 Y:103.91 Z:2.61 E:1.11 [INFO] RECV: T:215.4 /215.0 B:60.1 /60.0 T0:215.4 /215.0 @:45 B@:14 P:0.0 A:39.7 [INFO] RECV: X:129.87 Y:109.83 Z:2.45 E:2.72 Count X: 128.38 Y:95.12 Z:2.60 E:1.41 [INFO] RECV: NORMAL MODE: Percent done: 27; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: SILENT MODE: Percent done: 26; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: NORMAL MODE: Percent done: 27; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: SILENT MODE: Percent done: 27; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: T:215.2 /215.0 B:60.0 /60.0 T0:215.2 /215.0 @:48 B@:37 P:0.0 A:39.7 [INFO] RECV: X:127.92 Y:108.81 Z:2.45 E:1.52 Count X: 119.42 Y:103.15 Z:2.62 E:1.71 [INFO] RECV: T:215.4 /215.0 B:60.0 /60.0 T0:215.4 /215.0 @:43 B@:37 P:0.0 A:39.5 [INFO] RECV: X:128.70 Y:108.03 Z:2.45 E:2.74 Count X: 128.67 Y:97.07 Z:2.60 E:2.01 [INFO] RECV: T:214.9 /215.0 B:60.0 /60.0 T0:214.9 /215.0 @:51 B@:29 P:0.0 A:39.8 [INFO] RECV: X:128.70 Y:104.66 Z:2.45 E:2.93 Count X: 119.19 Y:101.92 Z:2.62 E:2.31 [INFO] RECV: T:214.8 /215.0 B:60.1 /60.0 T0:214.8 /215.0 @:53 B@:21 P:0.0 A:39.7 [INFO] RECV: X:122.98 Y:108.70 Z:2.45 E:3.09 Count X: 128.91 Y:97.34 Z:2.60 E:2.62 [INFO] RECV: T:214.7 /215.0 B:60.0 /60.0 T0:214.7 /215.0 @:55 B@:28 P:0.0 A:39.8 [INFO] RECV: X:121.30 Y:102.31 Z:2.45 E:-1.20 Count X: 127.51 Y:99.39 Z:2.60 E:2.93 [INFO] RECV: T:214.9 /215.0 B:60.1 /60.0 T0:214.9 /215.0 @:52 B@:12 P:0.0 A:39.6 [INFO] RECV: X:128.91 Y:101.09 Z:2.60 E:0.36 Count X: 122.30 Y:97.87 Z:2.61 E:3.50 [INFO] RECV: T:214.9 /215.0 B:60.3 /60.0 T0:214.9 /215.0 @:52 B@:0 P:0.0 A:39.7 [INFO] RECV: X:129.39 Y:100.61 Z:2.60 E:1.41 Count X: 123.98 Y:103.43 Z:2.76 E:0.06 [INFO] RECV: T:215.1 /215.0 B:60.2 /60.0 T0:215.1 /215.0 @:49 B@:0 P:0.0 A:39.4 [INFO] RECV: X:129.39 Y:109.35 Z:2.60 E:1.55 Count X: 127.96 Y:95.65 Z:2.75 E:0.37 [INFO] RECV: NORMAL MODE: Percent done: 28; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: SILENT MODE: Percent done: 27; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: NORMAL MODE: Percent done: 28; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: SILENT MODE: Percent done: 28; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: T:215.2 /215.0 B:60.0 /60.0 T0:215.2 /215.0 @:47 B@:35 P:0.0 A:39.7 [INFO] RECV: X:129.63 Y:105.00 Z:2.60 E:2.05 Count X: 119.90 Y:100.58 Z:2.77 E:0.66 [INFO] RECV: T:215.3 /215.0 B:59.8 /60.0 T0:215.3 /215.0 @:46 B@:77 P:0.0 A:39.7 [INFO] RECV: X:120.13 Y:109.87 Z:2.60 E:2.27 Count X: 128.20 Y:101.52 Z:2.75 E:0.97 [INFO] RECV: T:215.1 /215.0 B:60.1 /60.0 T0:215.1 /215.0 @:50 B@:21 P:0.0 A:39.8 [INFO] RECV: X:129.87 Y:105.00 Z:2.60 E:2.65 Count X: 119.65 Y:95.55 Z:2.76 E:1.27 [INFO] RECV: T:215.3 /215.0 B:60.0 /60.0 T0:215.3 /215.0 @:45 B@:37 P:0.0 A:39.8 [INFO] RECV: X:128.81 Y:102.08 Z:2.75 E:1.52 Count X: 127.67 Y:104.14 Z:2.75 E:1.56 [INFO] RECV: echo:enqueing "M600" [INFO] RECV: LCD status changed [INFO] RECV: T:215.1 /215.0 B:60.2 /60.0 T0:215.1 /215.0 @:49 B@:1 P:0.0 A:39.8 [INFO] RECV: X:128.81 Y:102.08 Z:2.60 E:2.72 Count X: 121.60 Y:94.89 Z:2.76 E:1.87 [INFO] RECV: T:214.9 /215.0 B:60.0 /60.0 T0:214.9 /215.0 @:52 B@:43 P:0.0 A:39.8 [INFO] RECV: X:128.70 Y:105.34 Z:2.60 E:2.85 Count X: 125.98 Y:104.37 Z:2.76 E:2.17 [INFO] RECV: T:214.9 /215.0 B:60.0 /60.0 T0:214.9 /215.0 @:51 B@:44 P:0.0 A:39.8 [INFO] RECV: X:122.98 Y:101.30 Z:2.60 E:2.96 Count X: 122.36 Y:94.65 Z:2.76 E:2.47 [INFO] RECV: T:214.6 /215.0 B:59.8 /60.0 T0:214.6 /215.0 @:57 B@:81 P:0.0 A:39.9 [INFO] RECV: X:122.98 Y:101.30 Z:2.60 E:2.96 Count X: 127.64 Y:101.41 Z:2.90 E:1.52 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.7 /215.0 B:59.9 /60.0 T0:214.7 /215.0 @:55 B@:47 P:0.0 A:39.8 [INFO] RECV: X:122.98 Y:101.30 Z:29.60 E:0.96 Count X: 122.03 Y:95.81 Z:3.72 E:0.96 [INFO] RECV: T:214.7 /215.0 B:60.0 /60.0 T0:214.7 /215.0 @:55 B@:25 P:0.0 A:40.1 [INFO] RECV: X:122.98 Y:101.30 Z:29.60 E:0.96 Count X: 122.03 Y:95.81 Z:15.83 E:0.96 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.8 /215.0 B:60.1 /60.0 T0:214.8 /215.0 @:54 B@:17 P:0.0 A:39.8 [INFO] RECV: X:122.98 Y:101.30 Z:29.60 E:0.96 Count X: 122.03 Y:95.81 Z:27.94 E:0.96 [INFO] RECV: T:215.3 /215.0 B:60.0 /60.0 T0:215.3 /215.0 @:46 B@:32 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:0.96 Count X: 148.74 Y:64.99 Z:29.59 E:0.96 [INFO] RECV: echo:busy: processing [INFO] RECV: T:215.3 /215.0 B:59.8 /60.0 T0:215.3 /215.0 @:46 B@:81 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:0.96 Count X: 181.81 Y:26.88 Z:29.38 E:0.96 [INFO] RECV: T:215.2 /215.0 B:59.9 /60.0 T0:215.2 /215.0 @:47 B@:48 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:0.96 Count X: 209.97 Y:-5.55 Z:29.21 E:0.96 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.8 /215.0 B:60.0 /60.0 T0:215.8 /215.0 @:38 B@:31 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:0.96 Count X: 209.97 Y:-5.55 Z:29.21 E:0.96 [INFO] RECV: T:216.1 /215.0 B:59.8 /60.0 T0:216.1 /215.0 @:31 B@:65 P:0.0 A:40.0 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:0.96 Count X: 209.97 Y:-5.55 Z:29.21 E:0.96 [INFO] RECV: echo:busy: paused for user [INFO] RECV: LCD status changed [INFO] RECV: T:216.0 /215.0 B:60.1 /60.0 T0:216.0 /215.0 @:33 B@:17 P:0.0 A:40.0 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-44.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-12.76 [INFO] RECV: T:216.0 /215.0 B:60.0 /60.0 T0:216.0 /215.0 @:32 B@:36 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-51.50 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.8 /215.0 B:59.8 /60.0 T0:215.8 /215.0 @:34 B@:72 P:0.0 A:40.0 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-68.34 [INFO] RECV: T:215.6 /215.0 B:60.0 /60.0 T0:215.6 /215.0 @:37 B@:24 P:0.0 A:40.1 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.4 /215.0 B:60.0 /60.0 T0:215.4 /215.0 @:39 B@:25 P:0.0 A:40.2 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.3 /215.0 B:60.1 /60.0 T0:215.3 /215.0 @:40 B@:5 P:0.0 A:40.4 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.3 /215.0 B:60.0 /60.0 T0:215.3 /215.0 @:40 B@:25 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.3 /215.0 B:60.1 /60.0 T0:215.3 /215.0 @:39 B@:24 P:0.0 A:39.5 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.6 /215.0 B:60.2 /60.0 T0:215.6 /215.0 @:34 B@:0 P:0.0 A:40.5 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: LCD status changed [INFO] RECV: T:215.6 /215.0 B:60.1 /60.0 T0:215.6 /215.0 @:33 B@:18 P:0.0 A:40.3 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.6 /215.0 B:60.1 /60.0 T0:215.6 /215.0 @:26 B@:13 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.8 /215.0 B:60.0 /60.0 T0:215.8 /215.0 @:29 B@:32 P:0.0 A:40.1 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:216.0 /215.0 B:60.1 /60.0 T0:216.0 /215.0 @:24 B@:10 P:0.0 A:40.1 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:216.1 /215.0 B:60.2 /60.0 T0:216.1 /215.0 @:22 B@:3 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.7 /215.0 B:60.2 /60.0 T0:215.7 /215.0 @:28 B@:5 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.2 /215.0 B:60.2 /60.0 T0:215.2 /215.0 @:36 B@:0 P:0.0 A:40.0 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:214.7 /215.0 B:60.2 /60.0 T0:214.7 /215.0 @:42 B@:6 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:214.6 /215.0 B:60.3 /60.0 T0:214.6 /215.0 @:43 B@:0 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:214.9 /215.0 B:60.1 /60.0 T0:214.9 /215.0 @:38 B@:29 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.6 /215.0 B:60.1 /60.0 T0:215.6 /215.0 @:27 B@:30 P:0.0 A:39.5 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:215.7 /215.0 B:60.2 /60.0 T0:215.7 /215.0 @:24 B@:8 P:0.0 A:40.0 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:216.6 /215.0 B:60.1 /60.0 T0:216.6 /215.0 @:9 B@:32 P:0.0 A:39.9 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:216.3 /215.0 B:60.1 /60.0 T0:216.3 /215.0 @:13 B@:42 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.8 /215.0 B:60.2 /60.0 T0:215.8 /215.0 @:20 B@:17 P:0.0 A:40.1 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:214.4 /215.0 B:60.1 /60.0 T0:214.4 /215.0 @:44 B@:25 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:-79.04 Count X: 209.97 Y:-5.55 Z:29.21 E:-79.04 [INFO] RECV: T:215.1 /215.0 B:60.0 /60.0 T0:215.1 /215.0 @:31 B@:61 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:-64.19 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.7 /215.0 B:60.1 /60.0 T0:214.7 /215.0 @:37 B@:23 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:-43.57 [INFO] RECV: T:214.6 /215.0 B:60.1 /60.0 T0:214.6 /215.0 @:39 B@:29 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:-22.99 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.8 /215.0 B:59.9 /60.0 T0:214.8 /215.0 @:34 B@:68 P:0.0 A:39.4 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:-8.00 [INFO] RECV: T:214.7 /215.0 B:60.1 /60.0 T0:214.7 /215.0 @:36 B@:32 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:-4.65 [INFO] RECV: echo:busy: processing [INFO] RECV: T:215.2 /215.0 B:60.1 /60.0 T0:215.2 /215.0 @:29 B@:27 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:-1.29 [INFO] RECV: T:215.3 /215.0 B:60.1 /60.0 T0:215.3 /215.0 @:27 B@:22 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:2.08 [INFO] RECV: echo:busy: processing [INFO] RECV: T:215.3 /215.0 B:60.1 /60.0 T0:215.3 /215.0 @:27 B@:29 P:0.0 A:40.2 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:5.43 [INFO] RECV: T:215.1 /215.0 B:60.1 /60.0 T0:215.1 /215.0 @:30 B@:29 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:8.82 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.9 /215.0 B:60.1 /60.0 T0:214.9 /215.0 @:33 B@:21 P:0.0 A:39.5 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:12.30 [INFO] RECV: T:214.7 /215.0 B:60.1 /60.0 T0:214.7 /215.0 @:37 B@:28 P:0.0 A:39.2 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.65 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.7 /215.0 B:60.1 /60.0 T0:214.7 /215.0 @:37 B@:18 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: T:214.2 /215.0 B:60.2 /60.0 T0:214.2 /215.0 @:45 B@:9 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:214.5 /215.0 B:60.2 /60.0 T0:214.5 /215.0 @:40 B@:12 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: T:214.6 /215.0 B:60.2 /60.0 T0:214.6 /215.0 @:40 B@:15 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:214.5 /215.0 B:60.1 /60.0 T0:214.5 /215.0 @:42 B@:22 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: echo:busy: paused for user [INFO] RECV: T:214.9 /215.0 B:60.0 /60.0 T0:214.9 /215.0 @:35 B@:56 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: T:215.1 /215.0 B:59.9 /60.0 T0:215.1 /215.0 @:32 B@:64 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:15.96 Count X: 209.97 Y:-5.55 Z:29.21 E:15.96 [INFO] RECV: echo:busy: processing [INFO] RECV: T:215.0 /215.0 B:59.8 /60.0 T0:215.0 /215.0 @:34 B@:71 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: 209.97 Y:-5.55 Z:29.21 E:17.49 [INFO] RECV: T:215.3 /215.0 B:60.0 /60.0 T0:215.3 /215.0 @:29 B@:24 P:0.0 A:39.5 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: 209.97 Y:-5.55 Z:29.21 E:19.51 [INFO] RECV: echo:busy: processing [INFO] RECV: T:215.5 /215.0 B:59.8 /60.0 T0:215.5 /215.0 @:26 B@:67 P:0.0 A:39.6 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: 201.17 Y:4.63 Z:29.26 E:20.96 [INFO] RECV: T:215.5 /215.0 B:59.6 /60.0 T0:215.5 /215.0 @:26 B@:94 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: [INFO] RECV: echo:busy: processing [INFO] RECV: T:215.2 /215.0 B:60.0 /60.0 T0:215.2 /215.0 @:31 B@:21 P:0.0 A:39.7 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: 135.02 Y:80.88 Z:29.68 E:20.96 [INFO] RECV: T:214.5 /215.0 B:60.1 /60.0 T0:214.5 /215.0 @:42 B@:0 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: 122.03 Y:95.81 Z:22.85 E:20.96 [INFO] RECV: echo:busy: processing [INFO] RECV: T:214.4 /215.0 B:60.1 /60.0 T0:214.4 /215.0 @:43 B@:0 P:0.0 A:39.8 [INFO] RECV: X:211.00 Y:0.00 Z:29.60 E:20.96 Count X: 122.03 Y:95.81 Z:10.73 E:20.96 [INFO] RECV: echo:enqueing "M220 S100" [INFO] RECV: LCD status changed [INFO] RECV: T:213.9 /215.0 B:60.2 /60.0 T0:213.9 /215.0 @:53 B@:0 P:0.0 A:39.8 [INFO] RECV: X:128.91 Y:108.91 Z:2.75 E:-1.20 Count X: 126.88 Y:102.31 Z:2.75 E:3.13 [INFO] RECV: T:213.9 /215.0 B:60.2 /60.0 T0:213.9 /215.0 @:53 B@:0 P:0.0 A:39.7 [INFO] RECV: X:120.85 Y:100.85 Z:2.75 E:0.74 Count X: 120.86 Y:103.07 Z:2.77 E:-0.78 [INFO] RECV: NORMAL MODE: Percent done: 29; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: SILENT MODE: Percent done: 28; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: NORMAL MODE: Percent done: 29; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: SILENT MODE: Percent done: 29; print time remaining in mins: 10; Change in mins: -1 [INFO] RECV: T:214.0 /215.0 B:60.1 /60.0 T0:214.0 /215.0 @:52 B@:28 P:0.0 A:39.7 [INFO] RECV: X:129.39 Y:105.00 Z:2.75 E:1.48 Count X: 120.13 Y:98.32 Z:2.91 E:0.20 [INFO] RECV: T:214.2 /215.0 B:60.4 /60.0 T0:214.2 /215.0 @:50 B@:0 P:0.0 A:39.9 [INFO] RECV: X:129.63 Y:100.37 Z:2.75 E:1.98 Count X: 127.55 Y:103.66 Z:2.90 E:0.50 [INFO] RECV: T:214.5 /215.0 B:60.5 /60.0 T0:214.5 /215.0 @:45 B@:0 P:0.0 A:40.0 [INFO] RECV: X:129.63 Y:109.59 Z:2.75 E:2.12 Count X: 123.60 Y:95.36 Z:2.91 E:0.80 [INFO] RECV: T:214.8 /215.0 B:60.5 /60.0 T0:214.8 /215.0 @:40 B@:0 P:0.0 A:39.9 [INFO] RECV: X:129.87 Y:100.13 Z:2.75 E:2.57 Count X: 122.08 Y:103.91 Z:2.91 E:1.10 [INFO] RECV: T:215.3 /215.0 B:60.5 /60.0 T0:215.3 /215.0 @:33 B@:0 P:0.0 A:39.8 [INFO] RECV: X:129.87 Y:109.83 Z:2.75 E:2.72 Count X: 128.15 Y:95.12 Z:2.90 E:1.41 [INFO] RECV: T:215.0 /215.0 B:60.7 /60.0 T0:215.0 /215.0 @:38 B@:0 P:0.0 A:39.7 [INFO] RECV: X:127.92 Y:108.81 Z:2.75 E:1.52 Count X: 119.42 Y:103.44 Z:2.92 E:1.70 [INFO] RECV: T:215.0 /215.0 B:60.5 /60.0 T0:215.0 /215.0 @:39 B@:0 P:0.0 A:40.4 [INFO] RECV: X:128.70 Y:108.03 Z:2.75 E:2.74 Count X: 128.67 Y:96.75 Z:2.90 E:2.01 [INFO] RECV: T:214.8 /215.0 B:60.5 /60.0 T0:214.8 /215.0 @:42 B@:0 P:0.0 A:39.8 [INFO] RECV: X:128.70 Y:104.66 Z:2.75 E:2.93 Count X: 119.19 Y:102.22 Z:2.92 E:2.30 [INFO] RECV: T:214.3 /215.0 B:60.4 /60.0 T0:214.3 /215.0 @:50 B@:0 P:0.0 A:39.6 [INFO] RECV: X:122.98 Y:108.70 Z:2.75 E:3.09 Count X: 128.91 Y:97.04 Z:2.90 E:2.61 [INFO] RECV: T:214.6 /215.0 B:60.4 /60.0 T0:214.6 /215.0 @:46 B@:4 P:0.0 A:39.9 [INFO] RECV: X:121.30 Y:102.31 Z:2.75 E:-1.20 Count X: 127.09 Y:99.80 Z:2.90 E:2.92

FrHePo commented 9 months ago

The misalignment with 0.4 nozzle is the same.

cozmo14047 commented 9 months ago

@FrHePo @cozmo14047 Thanks for the bug report. I added few extra code lines to v3.13.2 to trouble shoot this and can't find any layer shift during the M600 color change. Edit: I'm using. 6 but also tried .4 and I have mmu attached if that makes a difference

Below part of the serial output:

  • At the 1st line the M600 is called and the printer waits to finish the movement until line 3
  • Line 3 is the position the printer stopped printing
  • Line 4 and 5 are the pause positions and unload/load filament
  • Line 6+7 are the reported positions just before it continues to extrude

As line 3 and line 6 are identical there should be no layer shift caused by the printer firmware.

1 2023-11-15 11:59:38,077 - Recv: X:139.88 Y:117.50 Z:0.20 E:5.16 Count X: 118.86 Y:120.25 Z:0.29 E:2.39
2 2023-11-15 11:59:38,083 - Recv: X:139.88 Y:117.50 Z:0.20 E:5.16 Count X: 118.66 Y:120.25 Z:0.29 E:2.39
3 2023-11-15 11:59:41,138 - Recv: X:110.32 Y:118.44 Z:0.20 E:6.09 Count X: 110.32 Y:118.44 Z:0.29 E:6.09
4 2023-11-15 11:59:58,225 - Recv: X:211.00 Y:0.00 Z:27.20 E:24.09 Count X: 211.00 Y:0.00 Z:27.47 E:19.11
5 2023-11-15 12:00:00,964 - Recv: X:211.00 Y:0.00 Z:27.20 E:24.09 Count X: 110.32 Y:118.44 Z:27.29 E:24.09
6 2023-11-15 12:00:02,088 - Recv: X:110.32 Y:118.44 Z:0.20 E:6.09 Count X: 110.32 Y:118.44 Z:0.29 E:6.09
7 2023-11-15 12:00:02,095 - Recv: X:110.32 Y:118.44 Z:0.20 E:6.09 Count X: 110.32 Y:118.44 Z:0.29 E:6.09

Please try to enable the position auto report with M155 S1 C4 or M155 S1 C5 for temperatures and positions.

Tested with MK404 and here a screenshot of a one layer print with a Tune -> Color change image

I am yet to try with this in the gcode color change but it's when I go change fillament via lcd menu.i have it not as bad as @frhepo but still pretty bad I can try the suggested commands

3d-gussner commented 9 months ago

@FrHePo Thanks for sharing the picture and log file. Let's see if we can find something.

cozmo14047 commented 9 months ago

@3d-gussner I can confirm I don't have the issue when setting color change in gcode I only have it using the lcd menu.

3d-gussner commented 9 months ago

I have been printing a test cube on real hardware with fw3.13.2 and had no layer shift with lcd tune color change

FrHePo commented 9 months ago

Well, thanks for testing, but of course I'm still wondering what could cause this. Supposing it's not the firmware what could it be then? Are there any settings that could cause this? I have modded my MK3S allright, but not anything having to do with the X- and Y-axis. My modding concerns the frame (bear) and the extruder.

3d-gussner commented 9 months ago

@FrHePo I have no idea why that would happen and so repeatable on your printer. Does that also happen when you inject a M600 gcode via serial while printing from SD card? Does that happen when you print a SD print with a M600 gcode command added within PrusaSlicer?

Do you use the FW3.13.2+7080 firmware ?

Wasn't there any need to use a custom version for the bear? @gregsaun Just to be sure is there any custom firmware for the bear needed?

cozmo14047 commented 9 months ago

@FrHePo I have no idea why that would happen and so repeatable on your printer. Does that also happen when you inject a M600 gcode via serial while printing from SD card? Does that happen when you print a SD print with a M600 gcode command added within PrusaSlicer?

Do you use the FW3.13.2+7080 firmware ?

Wasn't there any need to use a custom version for the bear? @gregsaun Just to be sure is there any custom firmware for the bear needed?

So I upgraded to 3.13.2 and now I don't have It so ? Can old firmware plus new slicer equal problems

3d-gussner commented 9 months ago

@cozmo14047 Glad that the newest firmware solved your issue. Which firmware version did you have before that caused the issue?

Please next time when you react to an issue also add some more information as we ask in the issue templates to avoid chasing an issue that my be related to an older or different firmware version.

cozmo14047 commented 9 months ago

@cozmo14047 Glad that the newest firmware solved your issue. Which firmware version did you have before that caused the issue?

Please next time when you react to an issue also add some more information as we ask in the issue templates to avoid chasing an issue that my be related to an older or different firmware version.

Yeah sorry about that firmware 3.11 now on 3.13.2

FrHePo commented 9 months ago

@FrHePo I have no idea why that would happen and so repeatable on your printer. Does that also happen when you inject a M600 gcode via serial while printing from SD card? Does that happen when you print a SD print with a M600 gcode command added within PrusaSlicer?

Do you use the FW3.13.2+7080 firmware ?

Wasn't there any need to use a custom version for the bear? @gregsaun Just to be sure is there any custom firmware for the bear needed?

@3d-gussner Yes, FW3.13.2+7080 is correct. No, there's no custom version of the firmware for the bear frame needed or available. I'll try the manual M600 tonight.

FrHePo commented 9 months ago

Yep, both ways produce the same misalignment. It's consistent ☹️

3d-gussner commented 9 months ago

@FrHePo I could prepare a debug firmware version 3.13.2 for you to see if there is anything going on with the firmware or the issue is somewhere else. If you want to try that please send me an email, you find it in my github account.

gudnimg commented 9 months ago

For what its worth, I'm unable to reproduce the issue on MK3S+ (Itriggered M600 using the Tune menu)

Coordinates after M600 parking is done

Before unparking is about to start:

After unparking is done:

gudnimg commented 9 months ago

This may be unrelated but I wonder if your Y-axis is experiencing a "crash"? 🤔

@FrHePo is crash detection enabled?

EDIT: If a crash happens on the Y-axis, on my MK3S+ all axis including the X-axis freeze in place and the extruder is not parked at the right location. Though the LCD shows M600 menu as if nothing happened.

With crash detection enabled, the printer will re-home when M600 is done.

FrHePo commented 9 months ago

@gudnimg Yes, crash detection is enabled and the position resuming is absolutely smooth.

FrHePo commented 9 months ago

I just got an idea. I'm using the Revo Micro as my hotend and therefore I also loaded E3DREVO_fw_MK3S_MK3S+_3_13_0_6873.hex. Maybe there's a connection? I'll reload only 3_13_2_7080 and see if it makes any difference.

FrHePo commented 9 months ago

I changed the firmware back to 3_11_0_4955. The cube was again printed with the same misalignment of 0.7mm.

FrHePo commented 9 months ago

@FrHePo I could prepare a debug firmware version 3.13.2 for you to see if there is anything going on with the firmware or the issue is somewhere else. If you want to try that please send me an email, you find it in my github account.

@3d-gussner Did you get my email?

FrHePo commented 9 months ago

Ok, I think I solved the riddle. Before the old filament can be removed the build-plate travels to the minimum Y-position. Because of my changes my minimum Y-position must be 0.7mm less than a standard MK3S. Therefore when the build-plate returns to the correct Y-position for the hotend to resume the print the build-plate travels 0.7 mm too far. For me this behaviour raises 2 questions: 1st: Why doesn't the firmware use the homing data to know the actual min and max Y-values of a printer? 2nd: Is it necessary that the build-plate travels to the extreme Y-position for changing the filament? Couldn't this Y-postion be +2 mm instead of 0mm? Thus leaving some space for tolerance?

3d-gussner commented 9 months ago

@3d-gussner Did you get my email?

No I just got github emails. Please send a direct email to 3d.gussner@gmail.com

3d-gussner commented 9 months ago

Because of my changes my minimum Y-position must be 0.7mm less than a standard MK3S. Therefore when the build-plate returns to the correct Y-position for the hotend to resume the print the build-plate travels 0.7 mm too far.

Okay at least you have found the issue. Your printer is different than the Bear or Stock printer.

1st: Why doesn't the firmware use the homing data to know the actual min and max Y-values of a printer?

2nd: Is it necessary that the build-plate travels to the extreme Y-position for changing the filament? Couldn't this Y-postion be +2 mm instead of 0mm? Thus leaving some space for tolerance?

We have defined FILAMENTCHANGE_[XPOS|YPOS|ZADD] = X211 Y0 Z+2 which are fine for our printers. And normally the Y axis can travel from Y-4 up to 212.5 so for the stock printers it has some tolerances.

Please consider to create an own Firmware/variant/.h file with the modifications and compile the firmware.

3d-gussner commented 9 months ago

@FrHePo Sorry but I have to remove the BUG label and we can't / won't fix it as all other stock and probably most Bear printers work well. As this is an open source firmware feel free to modify it to your needs, see my comment above.

If we change it other users may/will complain why the filament change position has changed and it is now "harder" to grab the filament when they print in that area.

FrHePo commented 9 months ago

Because of my changes my minimum Y-position must be 0.7mm less than a standard MK3S. Therefore when the build-plate returns to the correct Y-position for the hotend to resume the print the build-plate travels 0.7 mm too far.

Okay at least you have found the issue. Your printer is different than the Bear or Stock printer.

1st: Why doesn't the firmware use the homing data to know the actual min and max Y-values of a printer?

2nd: Is it necessary that the build-plate travels to the extreme Y-position for changing the filament? Couldn't this Y-postion be +2 mm instead of 0mm? Thus leaving some space for tolerance?

We have defined FILAMENTCHANGE_[XPOS|YPOS|ZADD] = X211 Y0 Z+2 which are fine for our printers. And normally the Y axis can travel from Y-4 up to 212.5 so for the stock printers it has some tolerances.

Please consider to create an own Firmware/variant/.h file with the modifications and compile the firmware.

@3d-gussner I still don't understand all of it. I checked the dimensions of my Bear-frame and especially the position of the Z-profiles. All dimensions are correct! If the Bear-frame should cause this misalignment then this should be the case for all people that upgraded to the bear-frame and that will be more than just a few people. Further my printer passes all tests and all configurations have been completed successfully! I still feel that the firmware should be checked and possibly improved on this point. A hard coded position could be unnecessarily inflexible in this case.

gregsaun commented 9 months ago

Hello @FrHePo ,

  1. Where does your Bear frame come from?
  2. Could you check the dimensions as explained in this page: https://github.com/gregsaun/prusa_i3_bear_upgrade/tree/master/optional_parts/bear_2.0_upgrade_mk2-2s-2.5-2.5s#printed-parts ? Do you have a 311 or 331mm length?
  3. Have you modified the Y motor mount, Y belt tensioner or y rods holder?
FrHePo commented 9 months ago

@gregsaun

  1. Lecktor Technologies OÜ - Värvi 5 C400-1 - 10621 Tallinn - Estonia
  2. The Y-axis extrusion length is 331mm
  3. No, all parts are original Bear.
  4. The distance between the Y-rodholders is 311mm. The distance between the left side bearings is 93.8mm. This results in a max. build-plate travel of 217.2mm. The position of the Z-profiles was determined with the printed build_helper_z.stl-part (106 +/- 0.5mm). Mine measured 105.6mm.
FrHePo commented 9 months ago

@gregsaun What do you think?

gregsaun commented 9 months ago

@FrHePo have you tried to disable crash detection?

FrHePo commented 9 months ago

No. A crash should trigger a LCD-message and there never was any such message. What about moving the Z-profiles some mm further to the back?

gregsaun commented 9 months ago

It would still be good to test with crash detection disabled, it is a quick test. If it doesn't work could you please open an issue here and copy paste the link of this issue? https://github.com/gregsaun/prusa_i3_bear_upgrade/issues/

Could you also add some pictures of your printer over there.

Thank you in advance

FrHePo commented 9 months ago

Ok. I'll do that.

FrHePo commented 9 months ago

Ok, I disabled the crash detection but the result is exactly the same as before. It's also very clear that when the build-plate travels to the change filament position it runs into the Y-limit (by 0.7mm) which is actually an unintended collision. If the firmware would use the homing data instead of using a hard coded position I think this problem would not arise. That way the firmware would be more "intelligent".

FrHePo commented 9 months ago

See also https://github.com/gregsaun/prusa_i3_bear_upgrade/issues/142

3d-gussner commented 9 months ago

@FrHePo Closing this issue as you have solved it with @gregsaun :hugs: If you don't agree to close it feel free to reopen it.