{"action":"created","issue":{"url":"https://api.github.com/repos/FormerLurker/Octolapse/issues/149","repository_url":"https://api.github.com/repos/FormerLurker/Octolapse","labels_url":"https://api.github.com/repos/FormerLurker/Octolapse/issues/149/labels{/name}","comments_url":"https://api.github.com/repos/FormerLurker/Octolapse/issues/149/comments","events_url":"https://api.github.com/repos/FormerLurker/Octolapse/issues/149/events","html_url":"https://github.com/FormerLurker/Octolapse/issues/149","id":316427463,"node_id":"MDU6SXNzdWUzMTY0Mjc0NjM=","number":149,"title":"Snapshots not stabilized.","user":{"login":"Shadowen","id":8551479,"node_id":"MDQ6VXNlcjg1NTE0Nzk=","avatar_url":"https://avatars1.githubusercontent.com/u/8551479?v=4","gravatar_id":"","url":"https://api.github.com/users/Shadowen","html_url":"https://github.com/Shadowen","followers_url":"https://api.github.com/users/Shadowen/followers","following_url":"https://api.github.com/users/Shadowen/following{/other_user}","gists_url":"https://api.github.com/users/Shadowen/gists{/gist_id}","starred_url":"https://api.github.com/users/Shadowen/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/Shadowen/subscriptions","organizations_url":"https://api.github.com/users/Shadowen/orgs","repos_url":"https://api.github.com/users/Shadowen/repos","events_url":"https://api.github.com/users/Shadowen/events{/privacy}","received_events_url":"https://api.github.com/users/Shadowen/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":{"login":"Shadowen","id":8551479,"node_id":"MDQ6VXNlcjg1NTE0Nzk=","avatar_url":"https://avatars1.githubusercontent.com/u/8551479?v=4","gravatar_id":"","url":"https://api.github.com/users/Shadowen","html_url":"https://github.com/Shadowen","followers_url":"https://api.github.com/users/Shadowen/followers","following_url":"https://api.github.com/users/Shadowen/following{/other_user}","gists_url":"https://api.github.com/users/Shadowen/gists{/gist_id}","starred_url":"https://api.github.com/users/Shadowen/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/Shadowen/subscriptions","organizations_url":"https://api.github.com/users/Shadowen/orgs","repos_url":"https://api.github.com/users/Shadowen/repos","events_url":"https://api.github.com/users/Shadowen/events{/privacy}","received_events_url":"https://api.github.com/users/Shadowen/received_events","type":"User","site_admin":false},"assignees":[{"login":"Shadowen","id":8551479,"node_id":"MDQ6VXNlcjg1NTE0Nzk=","avatar_url":"https://avatars1.githubusercontent.com/u/8551479?v=4","gravatar_id":"","url":"https://api.github.com/users/Shadowen","html_url":"https://github.com/Shadowen","followers_url":"https://api.github.com/users/Shadowen/followers","following_url":"https://api.github.com/users/Shadowen/following{/other_user}","gists_url":"https://api.github.com/users/Shadowen/gists{/gist_id}","starred_url":"https://api.github.com/users/Shadowen/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/Shadowen/subscriptions","organizations_url":"https://api.github.com/users/Shadowen/orgs","repos_url":"https://api.github.com/users/Shadowen/repos","events_url":"https://api.github.com/users/Shadowen/events{/privacy}","received_events_url":"https://api.github.com/users/Shadowen/received_events","type":"User","site_admin":false}],"milestone":null,"comments":43,"created_at":"2018-04-20T22:18:38Z","updated_at":"2018-07-03T23:11:54Z","closed_at":null,"author_association":"COLLABORATOR","body":"#### If this is a feature request describe it here\r\nMy snapshots aren't stabilized properly. The printer is still doing the stabilization movement, but the snapshot appears to be taken at the wrong time.\r\n\r\n#### Version of Octolapse\r\nOctolapse Version: Octolapse v0.3.1\r\n\r\n\r\n#### Version of OctoPrint\r\nOctoPrint Version: OctoPrint 1.3.8\r\n\r\n\r\n#### When you ran into the problem, did you have diagnostic logging enabled?\r\nDiagnostic Logging was Enabled: YES\r\n\r\n\r\n#### What were you doing when the problem occurred\r\nRun OctoLapse-enabled print. Settings as below.\r\n\r\n#### What should have happened?\r\nStabilized print at printer's rear-left corner.\r\n\r\n\r\n#### What happened instead?\r\nStabilization occurred, but snapshots were happening while the extruder head was still on the print, rather than in the corner. Likely snapshots were mis-timed.\r\n\r\n\r\n#### Operating System running OctoPrint and Octolapse\r\nOS Name: OctoPi\r\nOs Version: OctoPi 0.14.0\r\n\r\n\r\n#### Printer model & used firmware incl. version\r\nPrinter Model: Maker Select v2.1 w/ RAMPS v1.4, Chimera toolhead (dual extrusion)\r\n**Entire print executed with T1 (second tool).** This may be relevant as T0 is unheated/unused throughout the entire print.\r\nPrinter Firmware Version: Slightly modified Marlin 1.1.x (bugfix branch)\r\n\r\n\r\n#### Link to the gcode file you were printing when the problem occurred\r\nLink to Gcode File: [CuteOcto.gcode](https://gist.github.com/Shadowen/9408f930112650ca50283b3bdf07942c)\r\n\r\n\r\n#### Link to settings.json\r\nLink to settings.json with all passwords removed: [Settings.json](https://gist.github.com/Shadowen/7c537ad5df6ed8090ca61cddbe2bc3a9)\r\nStabilization: Fixed - Extruder at Back Left (default)\r\nX: Relative Coordinate 1%\r\nY: Relative Coordinate 99%\r\n**??? What are relative coordinates?**\r\n\r\n#### Link to plugin_octolapse.log\r\nLink to plugin_octolapse.log:  [octolapse.log](https://gist.github.com/Shadowen/3f0d07f3ad114fc0f9a84e4fa25396c4)\r\n\r\n\r\n#### Link to octoprint.log\r\nLink to octoprint.log:  [octoprint.log](https://gist.github.com/Shadowen/2a00869eaa3fb2c1bbb1f694028ccb9e)\r\n\r\n#### Screenshots and/or videos of the problem:\r\nScreenshot/Video Links: \r\n[Attempt 1](https://youtu.be/1gEojcEU9yo) - stabilization fails halfway through. Print was done in Vase mode. Gcode not provided. It's not clear which file the logs pertain to.\r\n[Video](https://youtu.be/EHZwgHM0mCY) - stabilization never occurs. Print was done in normal mode (0.18 first layer height, 0.2 layer height). Print is sliced at 50% size."},"comment":{"url":"https://api.github.com/repos/FormerLurker/Octolapse/issues/comments/402317379","html_url":"https://github.com/FormerLurker/Octolapse/issues/149#issuecomment-402317379","issue_url":"https://api.github.com/repos/FormerLurker/Octolapse/issues/149","id":402317379,"node_id":"MDEyOklzc3VlQ29tbWVudDQwMjMxNzM3OQ==","user":{"login":"FormerLurker","id":33363128,"node_id":"MDQ6VXNlcjMzMzYzMTI4","avatar_url":"https://avatars1.githubusercontent.com/u/33363128?v=4","gravatar_id":"","url":"https://api.github.com/users/FormerLurker","html_url":"https://github.com/FormerLurker","followers_url":"https://api.github.com/users/FormerLurker/followers","following_url":"https://api.github.com/users/FormerLurker/following{/other_user}","gists_url":"https://api.github.com/users/FormerLurker/gists{/gist_id}","starred_url":"https://api.github.com/users/FormerLurker/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/FormerLurker/subscriptions","organizations_url":"https://api.github.com/users/FormerLurker/orgs","repos_url":"https://api.github.com/users/FormerLurker/repos","events_url":"https://api.github.com/users/FormerLurker/events{/privacy}","received_events_url":"https://api.github.com/users/FormerLurker/received_events","type":"User","site_admin":false},"created_at":"2018-07-03T23:11:54Z","updated_at":"2018-07-03T23:11:54Z","author_association":"OWNER","body":"@Xenomorphdelombre, thanks for trying it out.  Once again the log file has proven to be very useful.\r\nThe log indicates that approx 0.250 seconds passed as expected, but something else triggered the snapshot early.  I have a hunch that the G92 commands used to reset the E axis to 0 are somehow causing your printer to report the current position, which may be triggering the snapshot early!  If this is the case your timelapse should work when all of the G92s have been removed, and I'll know where to start working next!\r\n\r\nHere are some interesting logs leading me to this conclusion:\r\n\r\n```\r\n2018-07-03 22:06:32,698 Sent to printer: Command Type:None, gcode:G92, cmd: G92 E0, tags: set(['fileline:627', 'source:file', 'filepos:24516'])\r\n2018-07-03 22:06:32,705 Received from printer: line:X:149.80 Y:147.30 Z:0.45 E:0.00 Count X:10265 Y:11688 Z:180\r\n2018-07-03 22:06:32,707 Printer event received:PositionUpdate.\r\n2018-07-03 22:06:32,711 Received from printer: line:ok\r\n```\r\n\r\n\r\nIn the above a G92 was sent and a position response was IMMEDIATELY provided.  Here is another section where the snapshot gcode is being queued:\r\n\r\n\r\n```\r\n2018-07-03 22:08:38,856 Position Change - G1 X110.000 Y110.000 F3000 - Absolute Move From(X:70.6,Y:78.1,Z:0.65,E:321.4844) - To(X:110.0,Y:110.0,Z:0.65,E:321.4844) \r\n2018-07-03 22:08:38,857 Extruder Changed: E:0.0, Retraction:4.5 IsExtruding:False-False, IsExtrudingStart:False-False, IsPrimed:False-False, IsRetractingStart:True-False, IsRetracting:True-False, IsPartiallyRetracted:False-False, IsRetracted:True-True, IsDetractingStart:False-False, IsDetracting:False-False, IsDetracted:False-False\r\n2018-07-03 22:08:38,857 Queuing Command: Command Type:None, gcode:M400, cmd: M400, tags: set(['trigger:printer.commands', 'plugin:octolapse', 'source:plugin', 'snapshot_gcode'])\r\n2018-07-03 22:08:38,858 Queuing Command: Command Type:None, gcode:M114, cmd: M114, tags: set(['trigger:printer.commands', 'plugin:octolapse', 'source:plugin', 'snapshot_gcode'])\r\n2018-07-03 22:08:38,949 Received from printer: line:ok\r\n\r\n2018-07-03 22:08:38,957 Sent to printer: Command Type:None, gcode:G1, cmd: G1 Z0.650 F1000, tags: set(['fileline:1166', 'source:file', 'filepos:40627'])\r\n2018-07-03 22:08:38,959 Printer event received:ZChange.\r\n2018-07-03 22:08:38,979 Received from printer: line:ok\r\n\r\n2018-07-03 22:08:38,982 Sent to printer: Command Type:None, gcode:G1, cmd: G1 E0.0000 F2400, tags: set(['fileline:1167', 'source:file', 'filepos:40645'])\r\n2018-07-03 22:08:39,135 Received from printer: line:ok\r\n\r\n2018-07-03 22:08:39,139 Sent to printer: Command Type:None, gcode:G92, cmd: G92 E0, tags: set(['filepos:40653', 'source:file', 'fileline:1168'])\r\n2018-07-03 22:08:39,156 Received from printer: line:X:70.60 Y:146.90 Z:0.65 E:0.00 Count X:7352 Y:14670 Z:180\r\n\r\n2018-07-03 22:08:39,162 Received from printer: line:ok\r\n\r\n2018-07-03 22:08:39,164 Printer event received:PositionUpdate.\r\n2018-07-03 22:08:39,165 Sent to printer: Command Type:None, gcode:G1, cmd: G1 X70.600 Y78.100 E2.2654 F3360, tags: set(['source:file', 'filepos:40687', 'fileline:1169'])\r\n2018-07-03 22:08:39,166 Taking a snapshot.\r\n```\r\n\r\n\r\nNotice that a position update was received immediately after the G2 command was sent to the printer, even before the M114 command was sent (though it was queued...).  I suspect G92 is causing this somehow, though I'm not 100% sure how to handle it ATM.\r\n\r\nWould you mind trying something for me?  I know it sounds crazy, but can you try to reslice your file using relative E coordinates (you are currently using absolute)?  I can help you figure out how to do this if you let me know what slicer you are using.  You'll also want to double check your start gcode and see if you find any M82s and switch them with M83 (which cannot be arbitrarily done, so be careful here).  Also you'll want to search for G92 commands to make sure that one isn't being placed at the end of each layer.  I would be more than happy to review your code, but I suggest you use a very small model so that there is less code to review :)\r\n\r\nThanks for your help thus far!"}}
{"actor":{"display_login":"FormerLurker"}}