pattern_manager.py 87 KB

12345678910111213141516171819202122232425262728293031323334353637383940414243444546474849505152535455565758596061626364656667686970717273747576777879808182838485868788899091929394959697989910010110210310410510610710810911011111211311411511611711811912012112212312412512612712812913013113213313413513613713813914014114214314414514614714814915015115215315415515615715815916016116216316416516616716816917017117217317417517617717817918018118218318418518618718818919019119219319419519619719819920020120220320420520620720820921021121221321421521621721821922022122222322422522622722822923023123223323423523623723823924024124224324424524624724824925025125225325425525625725825926026126226326426526626726826927027127227327427527627727827928028128228328428528628728828929029129229329429529629729829930030130230330430530630730830931031131231331431531631731831932032132232332432532632732832933033133233333433533633733833934034134234334434534634734834935035135235335435535635735835936036136236336436536636736836937037137237337437537637737837938038138238338438538638738838939039139239339439539639739839940040140240340440540640740840941041141241341441541641741841942042142242342442542642742842943043143243343443543643743843944044144244344444544644744844945045145245345445545645745845946046146246346446546646746846947047147247347447547647747847948048148248348448548648748848949049149249349449549649749849950050150250350450550650750850951051151251351451551651751851952052152252352452552652752852953053153253353453553653753853954054154254354454554654754854955055155255355455555655755855956056156256356456556656756856957057157257357457557657757857958058158258358458558658758858959059159259359459559659759859960060160260360460560660760860961061161261361461561661761861962062162262362462562662762862963063163263363463563663763863964064164264364464564664764864965065165265365465565665765865966066166266366466566666766866967067167267367467567667767867968068168268368468568668768868969069169269369469569669769869970070170270370470570670770870971071171271371471571671771871972072172272372472572672772872973073173273373473573673773873974074174274374474574674774874975075175275375475575675775875976076176276376476576676776876977077177277377477577677777877978078178278378478578678778878979079179279379479579679779879980080180280380480580680780880981081181281381481581681781881982082182282382482582682782882983083183283383483583683783883984084184284384484584684784884985085185285385485585685785885986086186286386486586686786886987087187287387487587687787887988088188288388488588688788888989089189289389489589689789889990090190290390490590690790890991091191291391491591691791891992092192292392492592692792892993093193293393493593693793893994094194294394494594694794894995095195295395495595695795895996096196296396496596696796896997097197297397497597697797897998098198298398498598698798898999099199299399499599699799899910001001100210031004100510061007100810091010101110121013101410151016101710181019102010211022102310241025102610271028102910301031103210331034103510361037103810391040104110421043104410451046104710481049105010511052105310541055105610571058105910601061106210631064106510661067106810691070107110721073107410751076107710781079108010811082108310841085108610871088108910901091109210931094109510961097109810991100110111021103110411051106110711081109111011111112111311141115111611171118111911201121112211231124112511261127112811291130113111321133113411351136113711381139114011411142114311441145114611471148114911501151115211531154115511561157115811591160116111621163116411651166116711681169117011711172117311741175117611771178117911801181118211831184118511861187118811891190119111921193119411951196119711981199120012011202120312041205120612071208120912101211121212131214121512161217121812191220122112221223122412251226122712281229123012311232123312341235123612371238123912401241124212431244124512461247124812491250125112521253125412551256125712581259126012611262126312641265126612671268126912701271127212731274127512761277127812791280128112821283128412851286128712881289129012911292129312941295129612971298129913001301130213031304130513061307130813091310131113121313131413151316131713181319132013211322132313241325132613271328132913301331133213331334133513361337133813391340134113421343134413451346134713481349135013511352135313541355135613571358135913601361136213631364136513661367136813691370137113721373137413751376137713781379138013811382138313841385138613871388138913901391139213931394139513961397139813991400140114021403140414051406140714081409141014111412141314141415141614171418141914201421142214231424142514261427142814291430143114321433143414351436143714381439144014411442144314441445144614471448144914501451145214531454145514561457145814591460146114621463146414651466146714681469147014711472147314741475147614771478147914801481148214831484148514861487148814891490149114921493149414951496149714981499150015011502150315041505150615071508150915101511151215131514151515161517151815191520152115221523152415251526152715281529153015311532153315341535153615371538153915401541154215431544154515461547154815491550155115521553155415551556155715581559156015611562156315641565156615671568156915701571157215731574157515761577157815791580158115821583158415851586158715881589159015911592159315941595159615971598159916001601160216031604160516061607160816091610161116121613161416151616161716181619162016211622162316241625162616271628162916301631163216331634163516361637163816391640164116421643164416451646164716481649165016511652165316541655165616571658165916601661166216631664166516661667166816691670167116721673167416751676167716781679168016811682168316841685168616871688168916901691169216931694169516961697169816991700170117021703170417051706170717081709171017111712171317141715171617171718171917201721172217231724172517261727172817291730173117321733173417351736173717381739174017411742174317441745174617471748174917501751175217531754175517561757175817591760176117621763176417651766176717681769177017711772177317741775177617771778177917801781178217831784178517861787178817891790179117921793179417951796179717981799180018011802180318041805180618071808180918101811181218131814181518161817181818191820182118221823182418251826182718281829183018311832183318341835183618371838183918401841184218431844184518461847184818491850185118521853185418551856185718581859186018611862186318641865186618671868186918701871187218731874187518761877187818791880188118821883188418851886188718881889189018911892189318941895189618971898189919001901190219031904190519061907190819091910
  1. import os
  2. from zoneinfo import ZoneInfo
  3. import threading
  4. import time
  5. import random
  6. import logging
  7. from datetime import datetime, time as datetime_time
  8. from tqdm import tqdm
  9. from modules.connection import connection_manager
  10. from modules.core.state import state
  11. from math import pi, isnan, isinf
  12. import asyncio
  13. import json
  14. # Import for legacy support, but we'll use LED interface through state
  15. from modules.led.led_controller import effect_playing, effect_idle
  16. from modules.led.idle_timeout_manager import idle_timeout_manager
  17. import queue
  18. from dataclasses import dataclass
  19. from typing import Optional, Callable, Literal
  20. # Configure logging
  21. logger = logging.getLogger(__name__)
  22. # Global state
  23. THETA_RHO_DIR = './patterns'
  24. os.makedirs(THETA_RHO_DIR, exist_ok=True)
  25. # Execution time log file (JSON Lines format - one JSON object per line)
  26. EXECUTION_LOG_FILE = './execution_times.jsonl'
  27. async def wait_with_interrupt(
  28. condition_fn: Callable[[], bool],
  29. check_stop: bool = True,
  30. check_skip: bool = True,
  31. interval: float = 1.0,
  32. ) -> Literal['completed', 'stopped', 'skipped']:
  33. """
  34. Wait while condition_fn() returns True, with instant interrupt support.
  35. Uses asyncio.Event for instant response to stop/skip requests rather than
  36. polling at fixed intervals. This ensures users get immediate feedback when
  37. pressing stop or skip buttons.
  38. Args:
  39. condition_fn: Function that returns True while waiting should continue
  40. check_stop: Whether to respond to stop requests (default True)
  41. check_skip: Whether to respond to skip requests (default True)
  42. interval: How often to re-check condition_fn in seconds (default 1.0)
  43. Returns:
  44. 'completed' - condition_fn() returned False (normal completion)
  45. 'stopped' - stop was requested
  46. 'skipped' - skip was requested
  47. Example:
  48. result = await wait_with_interrupt(
  49. lambda: state.pause_requested or is_in_scheduled_pause_period()
  50. )
  51. if result == 'stopped':
  52. return # Exit pattern execution
  53. if result == 'skipped':
  54. break # Skip to next pattern
  55. """
  56. while condition_fn():
  57. result = await state.wait_for_interrupt(
  58. timeout=interval,
  59. check_stop=check_stop,
  60. check_skip=check_skip,
  61. )
  62. if result == 'stopped':
  63. return 'stopped'
  64. if result == 'skipped':
  65. return 'skipped'
  66. # 'timeout' means we should re-check condition_fn
  67. return 'completed'
  68. def log_execution_time(pattern_name: str, table_type: str, speed: int, actual_time: float,
  69. total_coordinates: int, was_completed: bool):
  70. """Log pattern execution time to JSON Lines file for analysis.
  71. Args:
  72. pattern_name: Name of the pattern file
  73. table_type: Type of table (e.g., 'dune_weaver', 'dune_weaver_mini')
  74. speed: Speed setting used (0-255)
  75. actual_time: Actual execution time in seconds (excluding pauses)
  76. total_coordinates: Total number of coordinates in the pattern
  77. was_completed: Whether the pattern completed normally (not stopped/skipped)
  78. """
  79. # Format time as HH:MM:SS
  80. hours, remainder = divmod(int(actual_time), 3600)
  81. minutes, seconds = divmod(remainder, 60)
  82. time_formatted = f"{hours:02d}:{minutes:02d}:{seconds:02d}"
  83. log_entry = {
  84. "timestamp": datetime.now().isoformat(),
  85. "pattern_name": pattern_name,
  86. "table_type": table_type or "unknown",
  87. "speed": speed,
  88. "actual_time_seconds": round(actual_time, 2),
  89. "actual_time_formatted": time_formatted,
  90. "total_coordinates": total_coordinates,
  91. "completed": was_completed
  92. }
  93. try:
  94. with open(EXECUTION_LOG_FILE, 'a') as f:
  95. f.write(json.dumps(log_entry) + '\n')
  96. logger.info(f"Execution time logged: {pattern_name} - {time_formatted} (speed: {speed}, table: {table_type})")
  97. except Exception as e:
  98. logger.error(f"Failed to log execution time: {e}")
  99. def get_last_completed_execution_time(pattern_name: str, speed: float) -> Optional[dict]:
  100. """Get the last completed execution time for a pattern at a specific speed.
  101. Args:
  102. pattern_name: Name of the pattern file (e.g., 'circle.thr')
  103. speed: Speed setting to match
  104. Returns:
  105. Dict with execution time info if found, None otherwise.
  106. Format: {"actual_time_seconds": float, "actual_time_formatted": str, "timestamp": str}
  107. """
  108. if not os.path.exists(EXECUTION_LOG_FILE):
  109. return None
  110. try:
  111. matching_entry = None
  112. with open(EXECUTION_LOG_FILE, 'r') as f:
  113. for line in f:
  114. line = line.strip()
  115. if not line:
  116. continue
  117. try:
  118. entry = json.loads(line)
  119. # Only consider fully completed patterns (100% finished)
  120. if (entry.get('completed', False) and
  121. entry.get('pattern_name') == pattern_name and
  122. entry.get('speed') == speed):
  123. # Keep the most recent match (last one in file)
  124. matching_entry = entry
  125. except json.JSONDecodeError:
  126. continue
  127. if matching_entry:
  128. return {
  129. "actual_time_seconds": matching_entry.get('actual_time_seconds'),
  130. "actual_time_formatted": matching_entry.get('actual_time_formatted'),
  131. "timestamp": matching_entry.get('timestamp')
  132. }
  133. return None
  134. except Exception as e:
  135. logger.error(f"Failed to read execution time log: {e}")
  136. return None
  137. def get_pattern_execution_history(pattern_name: str) -> Optional[dict]:
  138. """Get the most recent completed execution for a pattern (any speed).
  139. Args:
  140. pattern_name: Name of the pattern file (e.g., 'circle.thr')
  141. Returns:
  142. Dict with execution time info if found, None otherwise.
  143. Format: {"actual_time_seconds": float, "actual_time_formatted": str,
  144. "speed": int, "timestamp": str}
  145. """
  146. if not os.path.exists(EXECUTION_LOG_FILE):
  147. return None
  148. try:
  149. matching_entry = None
  150. with open(EXECUTION_LOG_FILE, 'r') as f:
  151. for line in f:
  152. line = line.strip()
  153. if not line:
  154. continue
  155. try:
  156. entry = json.loads(line)
  157. # Only consider fully completed patterns
  158. if (entry.get('completed', False) and
  159. entry.get('pattern_name') == pattern_name):
  160. # Keep the most recent match (last one in file)
  161. matching_entry = entry
  162. except json.JSONDecodeError:
  163. continue
  164. if matching_entry:
  165. return {
  166. "actual_time_seconds": matching_entry.get('actual_time_seconds'),
  167. "actual_time_formatted": matching_entry.get('actual_time_formatted'),
  168. "speed": matching_entry.get('speed'),
  169. "timestamp": matching_entry.get('timestamp')
  170. }
  171. return None
  172. except Exception as e:
  173. logger.error(f"Failed to read execution time log: {e}")
  174. return None
  175. # Asyncio primitives - initialized lazily to avoid event loop issues
  176. # These must be created in the context of the running event loop
  177. pause_event: Optional[asyncio.Event] = None
  178. pattern_lock: Optional[asyncio.Lock] = None
  179. progress_update_task = None
  180. def get_pause_event() -> asyncio.Event:
  181. """Get or create the pause event in the current event loop."""
  182. global pause_event
  183. if pause_event is None:
  184. pause_event = asyncio.Event()
  185. pause_event.set() # Initially not paused
  186. return pause_event
  187. def get_pattern_lock() -> asyncio.Lock:
  188. """Get or create the pattern lock in the current event loop."""
  189. global pattern_lock
  190. if pattern_lock is None:
  191. pattern_lock = asyncio.Lock()
  192. return pattern_lock
  193. # Cache timezone at module level - read once per session (cleared when user changes timezone)
  194. _cached_timezone = None
  195. _cached_zoneinfo = None
  196. def _get_timezone():
  197. """Get and cache the timezone for Still Sands. Uses user-selected timezone if set, otherwise system timezone."""
  198. global _cached_timezone, _cached_zoneinfo
  199. if _cached_timezone is not None:
  200. return _cached_zoneinfo
  201. user_tz = 'UTC' # Default fallback
  202. # First, check if user has selected a specific timezone in settings
  203. if state.scheduled_pause_timezone:
  204. user_tz = state.scheduled_pause_timezone
  205. logger.info(f"Still Sands using timezone: {user_tz} (user-selected)")
  206. else:
  207. # Fall back to system timezone detection
  208. try:
  209. if os.path.exists('/etc/host-timezone'):
  210. with open('/etc/host-timezone', 'r') as f:
  211. user_tz = f.read().strip()
  212. logger.info(f"Still Sands using timezone: {user_tz} (from host system)")
  213. # Fallback to /etc/timezone if host-timezone doesn't exist
  214. elif os.path.exists('/etc/timezone'):
  215. with open('/etc/timezone', 'r') as f:
  216. user_tz = f.read().strip()
  217. logger.info(f"Still Sands using timezone: {user_tz} (from container)")
  218. # Fallback to TZ environment variable
  219. elif os.environ.get('TZ'):
  220. user_tz = os.environ.get('TZ')
  221. logger.info(f"Still Sands using timezone: {user_tz} (from environment)")
  222. else:
  223. logger.info("Still Sands using timezone: UTC (system default)")
  224. except Exception as e:
  225. logger.debug(f"Could not read timezone: {e}")
  226. # Cache the timezone
  227. _cached_timezone = user_tz
  228. try:
  229. _cached_zoneinfo = ZoneInfo(user_tz)
  230. except Exception as e:
  231. logger.warning(f"Invalid timezone '{user_tz}', falling back to system time: {e}")
  232. _cached_zoneinfo = None
  233. return _cached_zoneinfo
  234. def is_in_scheduled_pause_period():
  235. """Check if current time falls within any scheduled pause period."""
  236. if not state.scheduled_pause_enabled or not state.scheduled_pause_time_slots:
  237. return False
  238. # Get cached timezone (user-selected or system default)
  239. tz_info = _get_timezone()
  240. try:
  241. # Get current time in user's timezone
  242. if tz_info:
  243. now = datetime.now(tz_info)
  244. else:
  245. now = datetime.now()
  246. except Exception as e:
  247. logger.warning(f"Error getting current time: {e}")
  248. now = datetime.now()
  249. current_time = now.time()
  250. current_weekday = now.strftime("%A").lower() # monday, tuesday, etc.
  251. for slot in state.scheduled_pause_time_slots:
  252. # Parse start and end times
  253. try:
  254. start_time = datetime_time.fromisoformat(slot['start_time'])
  255. end_time = datetime_time.fromisoformat(slot['end_time'])
  256. except (ValueError, KeyError):
  257. logger.warning(f"Invalid time format in scheduled pause slot: {slot}")
  258. continue
  259. # Check if this slot applies to today
  260. slot_applies_today = False
  261. days_setting = slot.get('days', 'daily')
  262. if days_setting == 'daily':
  263. slot_applies_today = True
  264. elif days_setting == 'weekdays':
  265. slot_applies_today = current_weekday in ['monday', 'tuesday', 'wednesday', 'thursday', 'friday']
  266. elif days_setting == 'weekends':
  267. slot_applies_today = current_weekday in ['saturday', 'sunday']
  268. elif days_setting == 'custom':
  269. custom_days = slot.get('custom_days', [])
  270. slot_applies_today = current_weekday in custom_days
  271. if not slot_applies_today:
  272. continue
  273. # Check if current time is within the pause period
  274. if start_time <= end_time:
  275. # Normal case: start and end are on the same day
  276. if start_time <= current_time <= end_time:
  277. return True
  278. else:
  279. # Time spans midnight: start is before midnight, end is after midnight
  280. if current_time >= start_time or current_time <= end_time:
  281. return True
  282. return False
  283. async def check_table_is_idle() -> bool:
  284. """
  285. Check if the table is currently idle by querying actual machine status.
  286. Returns True if idle, False if playing/moving.
  287. This checks the real machine state rather than relying on state variables,
  288. making it more reliable for detecting when table is truly idle.
  289. """
  290. # Use the connection_manager's is_machine_idle() function
  291. # Run it in a thread since it's a synchronous function
  292. return await asyncio.to_thread(connection_manager.is_machine_idle)
  293. def start_idle_led_timeout():
  294. """
  295. Start the idle LED timeout if enabled.
  296. Should be called whenever the idle effect is activated.
  297. """
  298. if not state.dw_led_idle_timeout_enabled:
  299. logger.debug("Idle LED timeout not enabled")
  300. return
  301. timeout_minutes = state.dw_led_idle_timeout_minutes
  302. if timeout_minutes <= 0:
  303. logger.debug("Idle LED timeout not configured (timeout <= 0)")
  304. return
  305. logger.debug(f"Starting idle LED timeout: {timeout_minutes} minutes")
  306. idle_timeout_manager.start_idle_timeout(
  307. timeout_minutes=timeout_minutes,
  308. state=state,
  309. check_idle_callback=check_table_is_idle
  310. )
  311. # Motion Control Thread Infrastructure
  312. @dataclass
  313. class MotionCommand:
  314. """Represents a motion command for the motion control thread."""
  315. command_type: str # 'move', 'stop', 'pause', 'resume', 'shutdown'
  316. theta: Optional[float] = None
  317. rho: Optional[float] = None
  318. speed: Optional[float] = None
  319. callback: Optional[Callable] = None
  320. future: Optional[asyncio.Future] = None
  321. class MotionControlThread:
  322. """Dedicated thread for hardware motion control operations."""
  323. def __init__(self):
  324. self.command_queue = queue.Queue()
  325. self.thread = None
  326. self.running = False
  327. self.paused = False
  328. def start(self):
  329. """Start the motion control thread with elevated priority."""
  330. if self.thread and self.thread.is_alive():
  331. return
  332. self.running = True
  333. self.thread = threading.Thread(target=self._motion_loop, daemon=True)
  334. self.thread.start()
  335. logger.info("Motion control thread started")
  336. def stop(self):
  337. """Stop the motion control thread."""
  338. if not self.running:
  339. return
  340. self.running = False
  341. # Send shutdown command
  342. self.command_queue.put(MotionCommand('shutdown'))
  343. if self.thread and self.thread.is_alive():
  344. self.thread.join(timeout=5.0)
  345. logger.info("Motion control thread stopped")
  346. def _motion_loop(self):
  347. """Main loop for the motion control thread."""
  348. logger.info("Motion control thread loop started")
  349. while self.running:
  350. try:
  351. # Get command with timeout to allow periodic checks
  352. command = self.command_queue.get(timeout=1.0)
  353. if command.command_type == 'shutdown':
  354. break
  355. elif command.command_type == 'move':
  356. self._execute_move(command)
  357. elif command.command_type == 'pause':
  358. self.paused = True
  359. elif command.command_type == 'resume':
  360. self.paused = False
  361. elif command.command_type == 'stop':
  362. # Clear any pending commands
  363. while not self.command_queue.empty():
  364. try:
  365. self.command_queue.get_nowait()
  366. except queue.Empty:
  367. break
  368. self.command_queue.task_done()
  369. except queue.Empty:
  370. # Timeout - continue loop for shutdown check
  371. continue
  372. except Exception as e:
  373. logger.error(f"Error in motion control thread: {e}")
  374. logger.info("Motion control thread loop ended")
  375. def _execute_move(self, command: MotionCommand):
  376. """Execute a move command in the motion thread."""
  377. try:
  378. # Wait if paused
  379. while self.paused and self.running:
  380. time.sleep(0.1)
  381. if not self.running:
  382. return
  383. # Execute the actual motion using sync version
  384. self._move_polar_sync(command.theta, command.rho, command.speed)
  385. # Signal completion if future provided
  386. if command.future and not command.future.done():
  387. command.future.get_loop().call_soon_threadsafe(
  388. command.future.set_result, None
  389. )
  390. except Exception as e:
  391. logger.error(f"Error executing move command: {e}")
  392. if command.future and not command.future.done():
  393. command.future.get_loop().call_soon_threadsafe(
  394. command.future.set_exception, e
  395. )
  396. def _move_polar_sync(self, theta: float, rho: float, speed: Optional[float] = None):
  397. """Synchronous version of move_polar for use in motion thread."""
  398. # Check for valid machine position (can be None if homing failed)
  399. if state.machine_x is None or state.machine_y is None:
  400. logger.error("Cannot execute move: machine position unknown (homing may have failed)")
  401. logger.error("Please home the machine before running patterns")
  402. state.stop_requested = True
  403. return
  404. # This is the original sync logic but running in dedicated thread
  405. if state.table_type == 'dune_weaver_mini':
  406. x_scaling_factor = 2
  407. y_scaling_factor = 3.7
  408. else:
  409. x_scaling_factor = 2
  410. y_scaling_factor = 5
  411. delta_theta = theta - state.current_theta
  412. delta_rho = rho - state.current_rho
  413. x_increment = delta_theta * 100 / (2 * pi * x_scaling_factor)
  414. y_increment = delta_rho * 100 / y_scaling_factor
  415. x_total_steps = state.x_steps_per_mm * (100/x_scaling_factor)
  416. y_total_steps = state.y_steps_per_mm * (100/y_scaling_factor)
  417. offset = x_increment * (x_total_steps * x_scaling_factor / (state.gear_ratio * y_total_steps * y_scaling_factor))
  418. if state.table_type == 'dune_weaver_mini' or state.y_steps_per_mm == 546:
  419. y_increment -= offset
  420. else:
  421. y_increment += offset
  422. new_x_abs = state.machine_x + x_increment
  423. new_y_abs = state.machine_y + y_increment
  424. # Use provided speed or fall back to state.speed
  425. actual_speed = speed if speed is not None else state.speed
  426. # Validate coordinates before sending to prevent GRBL error:2
  427. if isnan(new_x_abs) or isnan(new_y_abs) or isinf(new_x_abs) or isinf(new_y_abs):
  428. logger.error(f"Motion thread: Invalid coordinates detected - X:{new_x_abs}, Y:{new_y_abs}")
  429. logger.error(f" theta:{theta}, rho:{rho}, current_theta:{state.current_theta}, current_rho:{state.current_rho}")
  430. logger.error(f" x_steps_per_mm:{state.x_steps_per_mm}, y_steps_per_mm:{state.y_steps_per_mm}, gear_ratio:{state.gear_ratio}")
  431. state.stop_requested = True
  432. return
  433. # Call sync version of send_grbl_coordinates in this thread
  434. # Use 2 decimal precision to reduce GRBL parsing overhead
  435. self._send_grbl_coordinates_sync(round(new_x_abs, 2), round(new_y_abs, 2), actual_speed)
  436. # Update state
  437. state.current_theta = theta
  438. state.current_rho = rho
  439. state.machine_x = new_x_abs
  440. state.machine_y = new_y_abs
  441. def _send_grbl_coordinates_sync(self, x: float, y: float, speed: int = 600, timeout: int = 2, home: bool = False):
  442. """Synchronous version of send_grbl_coordinates for motion thread.
  443. Waits for 'ok' with a timeout. GRBL sends 'ok' after the move completes,
  444. which can take many seconds at slow speeds. We use a generous timeout
  445. (120 seconds) to handle slow movements, but prevent indefinite hangs.
  446. Includes retry logic for serial corruption errors (common on Pi 3B+).
  447. """
  448. gcode = f"$J=G91 G21 Y{y:.2f} F{speed}" if home else f"G1 X{x:.2f} Y{y:.2f} F{speed}"
  449. max_wait_time = 120 # Maximum seconds to wait for 'ok' response
  450. max_corruption_retries = 10 # Max retries for corruption-type errors
  451. max_timeout_retries = 10 # Max retries for timeout (lost 'ok' response)
  452. corruption_retry_count = 0
  453. timeout_retry_count = 0
  454. # GRBL error codes that indicate likely serial corruption (syntax errors)
  455. # These are recoverable by resending the command
  456. corruption_error_codes = {
  457. 'error:1', # Expected command letter
  458. 'error:2', # Bad number format
  459. 'error:20', # Invalid gcode ID (e.g., G5s instead of G53)
  460. 'error:21', # Invalid gcode command value
  461. 'error:22', # Invalid gcode command value in negative
  462. 'error:23', # Invalid gcode command value in decimal
  463. }
  464. while True:
  465. # Check stop_requested at the start of each iteration
  466. if state.stop_requested:
  467. logger.debug("Motion thread: Stop requested, aborting command")
  468. return False
  469. try:
  470. # Clear any stale input data before sending to prevent interleaving
  471. # This helps with timing issues on slower UARTs like Pi 3B+
  472. if hasattr(state.conn, 'reset_input_buffer'):
  473. state.conn.reset_input_buffer()
  474. logger.debug(f"Motion thread sending G-code: {gcode}")
  475. state.conn.send(gcode + "\n")
  476. # Small delay for serial buffer to stabilize on slower UARTs
  477. # Prevents timing-related corruption on Pi 3B+
  478. time.sleep(0.005)
  479. # Wait for 'ok' with timeout
  480. wait_start = time.time()
  481. while True:
  482. # Check stop_requested while waiting
  483. if state.stop_requested:
  484. logger.debug("Motion thread: Stop requested while waiting for response")
  485. return False
  486. # Check for timeout
  487. elapsed = time.time() - wait_start
  488. if elapsed > max_wait_time:
  489. logger.warning(f"Motion thread: Timeout ({max_wait_time}s) waiting for 'ok' response")
  490. logger.warning(f"Motion thread: Failed command was: {gcode}")
  491. # Attempt to recover by checking machine status
  492. # The 'ok' might have been lost but command may have executed
  493. logger.info("Motion thread: Attempting timeout recovery - checking machine status")
  494. logger.info(f"Motion thread: Current retry counts - timeout: {timeout_retry_count}/{max_timeout_retries}, corruption: {corruption_retry_count}/{max_corruption_retries}")
  495. try:
  496. # Check connection state first
  497. conn_type = type(state.conn).__name__ if state.conn else "None"
  498. logger.info(f"Motion thread: Connection type: {conn_type}")
  499. if not state.conn:
  500. logger.error("Motion thread: Connection object is None!")
  501. raise Exception("Connection is None")
  502. # Clear buffer first
  503. if hasattr(state.conn, 'reset_input_buffer'):
  504. state.conn.reset_input_buffer()
  505. logger.info("Motion thread: Input buffer cleared")
  506. else:
  507. logger.warning("Motion thread: Connection has no reset_input_buffer method")
  508. # Check if there's data waiting before we send
  509. if hasattr(state.conn, 'in_waiting'):
  510. waiting = state.conn.in_waiting()
  511. logger.info(f"Motion thread: Bytes waiting in buffer after clear: {waiting}")
  512. # Send status query
  513. logger.info("Motion thread: Sending status query '?'...")
  514. state.conn.send("?\n")
  515. time.sleep(0.2)
  516. logger.info("Motion thread: Status query sent, reading responses...")
  517. # Try to read status response
  518. status_response = None
  519. responses_received = []
  520. for i in range(10):
  521. resp = state.conn.readline()
  522. if resp:
  523. responses_received.append(resp)
  524. logger.info(f"Motion thread: Recovery response [{i+1}/10]: '{resp}'")
  525. if '<' in resp or 'Idle' in resp or 'Run' in resp or 'Hold' in resp or 'Alarm' in resp:
  526. status_response = resp
  527. logger.info(f"Motion thread: Found valid status response: '{resp}'")
  528. break
  529. # Also check for 'ok' that might have been delayed
  530. if resp.lower() == 'ok':
  531. logger.info("Motion thread: Received delayed 'ok' during recovery - SUCCESS")
  532. return True
  533. else:
  534. logger.debug(f"Motion thread: Recovery read [{i+1}/10]: no data (timeout)")
  535. time.sleep(0.05)
  536. # Log summary of what we received
  537. if responses_received:
  538. logger.info(f"Motion thread: Total responses received during recovery: {len(responses_received)}")
  539. logger.info(f"Motion thread: All responses: {responses_received}")
  540. else:
  541. logger.warning("Motion thread: No responses received during recovery - connection may be dead")
  542. if status_response:
  543. if 'Idle' in status_response:
  544. # Machine is idle - command likely completed, 'ok' was lost
  545. logger.info("Motion thread: Machine is Idle - assuming command completed (ok was lost) - SUCCESS")
  546. return True
  547. elif 'Run' in status_response:
  548. # Machine still running - extend timeout
  549. logger.info("Motion thread: Machine still running, extending wait time")
  550. wait_start = time.time() # Reset timeout
  551. continue
  552. elif 'Hold' in status_response:
  553. # Machine is in Hold state - attempt to resume
  554. logger.warning(f"Motion thread: Machine in Hold state: '{status_response}'")
  555. logger.info("Motion thread: Sending cycle start command '~' to resume from Hold...")
  556. # Send cycle start command to resume
  557. state.conn.send("~\n")
  558. time.sleep(0.3) # Give time for resume to process
  559. # Re-check status after resume attempt
  560. state.conn.send("?\n")
  561. time.sleep(0.2)
  562. # Read new status
  563. resume_response = None
  564. for _ in range(5):
  565. resp = state.conn.readline()
  566. if resp:
  567. logger.info(f"Motion thread: Post-resume response: '{resp}'")
  568. if '<' in resp:
  569. resume_response = resp
  570. break
  571. time.sleep(0.05)
  572. if resume_response:
  573. if 'Idle' in resume_response:
  574. logger.info("Motion thread: Machine resumed and is now Idle - SUCCESS")
  575. return True
  576. elif 'Run' in resume_response:
  577. logger.info("Motion thread: Machine resumed and running, extending wait time")
  578. wait_start = time.time()
  579. continue
  580. elif 'Hold' in resume_response:
  581. # Still in Hold - may need user intervention
  582. logger.warning(f"Motion thread: Still in Hold after resume: '{resume_response}'")
  583. else:
  584. logger.warning("Motion thread: No response after resume attempt")
  585. elif 'Alarm' in status_response:
  586. # Machine is in Alarm state - attempt to unlock
  587. logger.warning(f"Motion thread: Machine in ALARM state: '{status_response}'")
  588. logger.info("Motion thread: Sending $X to unlock from Alarm...")
  589. # Send unlock command
  590. state.conn.send("$X\n")
  591. time.sleep(0.5) # Give time for unlock to process
  592. # Re-check status after unlock attempt
  593. state.conn.send("?\n")
  594. time.sleep(0.2)
  595. # Read new status
  596. unlock_response = None
  597. for _ in range(5):
  598. resp = state.conn.readline()
  599. if resp:
  600. logger.info(f"Motion thread: Post-unlock response: '{resp}'")
  601. if '<' in resp:
  602. unlock_response = resp
  603. break
  604. time.sleep(0.05)
  605. if unlock_response:
  606. if 'Idle' in unlock_response:
  607. logger.info("Motion thread: Machine unlocked and is now Idle - retrying command")
  608. # Don't return True - we need to resend the failed command
  609. break # Break inner loop to retry the command
  610. elif 'Alarm' in unlock_response:
  611. # Still in Alarm - underlying issue persists (e.g., sensor triggered)
  612. logger.error(f"Motion thread: Still in ALARM after unlock: '{unlock_response}'")
  613. logger.error("Motion thread: Machine may need physical attention")
  614. state.stop_requested = True
  615. return False
  616. else:
  617. logger.warning("Motion thread: No response after unlock attempt")
  618. else:
  619. logger.warning(f"Motion thread: Unrecognized status response: '{status_response}'")
  620. else:
  621. logger.warning("Motion thread: No valid status response found in any received data")
  622. # No valid status response - connection may be dead
  623. timeout_retry_count += 1
  624. if timeout_retry_count <= max_timeout_retries:
  625. logger.warning(f"Motion thread: Recovery failed, will retry command ({timeout_retry_count}/{max_timeout_retries})")
  626. time.sleep(0.1)
  627. break # Break inner loop to resend command
  628. else:
  629. logger.error(f"Motion thread: Max timeout retries ({max_timeout_retries}) exceeded")
  630. except Exception as e:
  631. logger.error(f"Motion thread: Error during timeout recovery: {e}")
  632. import traceback
  633. logger.error(f"Motion thread: Traceback: {traceback.format_exc()}")
  634. # Max retries exceeded or recovery failed
  635. logger.error("=" * 60)
  636. logger.error("Motion thread: TIMEOUT RECOVERY FAILED - STOPPING PATTERN")
  637. logger.error(f" Failed command: {gcode}")
  638. logger.error(f" Timeout retries used: {timeout_retry_count}/{max_timeout_retries}")
  639. logger.error(f" Corruption retries used: {corruption_retry_count}/{max_corruption_retries}")
  640. logger.error(" Possible causes:")
  641. logger.error(" - Serial connection lost or unstable")
  642. logger.error(" - Hardware controller unresponsive")
  643. logger.error(" - USB power issue (try powered hub)")
  644. logger.error("=" * 60)
  645. state.stop_requested = True
  646. return False
  647. response = state.conn.readline()
  648. if response:
  649. logger.debug(f"Motion thread response: {response}")
  650. if response.lower() == "ok":
  651. logger.debug("Motion thread: Command execution confirmed.")
  652. # Reset corruption retry count on success
  653. if corruption_retry_count > 0:
  654. logger.info(f"Motion thread: Command succeeded after {corruption_retry_count} corruption retry(ies)")
  655. return True
  656. # Handle GRBL errors
  657. if response.lower().startswith("error"):
  658. error_code = response.lower().split()[0] if response else ""
  659. # Check if this is a corruption-type error (recoverable)
  660. if error_code in corruption_error_codes:
  661. corruption_retry_count += 1
  662. if corruption_retry_count <= max_corruption_retries:
  663. logger.warning(f"Motion thread: Likely serial corruption detected ({response})")
  664. logger.warning(f"Motion thread: Retrying command ({corruption_retry_count}/{max_corruption_retries}): {gcode}")
  665. # Clear buffer and wait longer before retry
  666. if hasattr(state.conn, 'reset_input_buffer'):
  667. state.conn.reset_input_buffer()
  668. time.sleep(0.02) # 20ms delay before retry
  669. break # Break inner loop to retry send
  670. else:
  671. logger.error(f"Motion thread: Max corruption retries ({max_corruption_retries}) exceeded")
  672. logger.error(f"Motion thread: GRBL error received: {response}")
  673. logger.error(f"Failed command: {gcode}")
  674. logger.error("Stopping pattern due to persistent serial corruption")
  675. state.stop_requested = True
  676. return False
  677. else:
  678. # Non-corruption error - stop immediately
  679. logger.error(f"Motion thread: GRBL error received: {response}")
  680. logger.error(f"Failed command: {gcode}")
  681. logger.error("Stopping pattern due to GRBL error")
  682. state.stop_requested = True
  683. return False
  684. # Handle GRBL alarms - machine needs attention
  685. if "alarm" in response.lower():
  686. logger.error(f"Motion thread: GRBL ALARM: {response}")
  687. logger.error("Machine alarm triggered - stopping pattern")
  688. state.stop_requested = True
  689. return False
  690. # FluidNC may echo commands back before sending 'ok'
  691. # Silently ignore echoed G-code commands (G0, G1, $J, etc.)
  692. if response.startswith(('G0', 'G1', 'G2', 'G3', '$J', 'M')):
  693. logger.debug(f"Motion thread: Ignoring echoed command: {response}")
  694. continue # Read next line to get 'ok'
  695. # Check for corruption indicator in MSG:ERR responses
  696. if 'MSG:ERR' in response and 'Bad GCode' in response:
  697. corruption_retry_count += 1
  698. if corruption_retry_count <= max_corruption_retries:
  699. logger.warning(f"Motion thread: Corrupted command detected: {response}")
  700. logger.warning(f"Motion thread: Retrying command ({corruption_retry_count}/{max_corruption_retries}): {gcode}")
  701. # Don't break yet - wait for the error:XX that follows
  702. continue
  703. # If we've exceeded retries, the error:XX handler above will catch it
  704. # Log truly unexpected responses
  705. logger.warning(f"Motion thread: Unexpected response: '{response}'")
  706. else:
  707. # Log periodically when waiting for response (every 30s)
  708. if int(elapsed) > 0 and int(elapsed) % 30 == 0 and elapsed - int(elapsed) < 0.1:
  709. logger.warning(f"Motion thread: Still waiting for 'ok' after {int(elapsed)}s for command: {gcode}")
  710. else:
  711. # Inner while loop completed without break - shouldn't happen normally
  712. # This means we hit timeout, which is handled above
  713. continue
  714. except Exception as e:
  715. error_str = str(e)
  716. logger.warning(f"Motion thread error sending command: {error_str}")
  717. # Immediately return for device not configured errors
  718. if "Device not configured" in error_str or "Errno 6" in error_str:
  719. logger.error(f"Motion thread: Device configuration error detected: {error_str}")
  720. state.stop_requested = True
  721. state.conn = None
  722. state.is_connected = False
  723. logger.info("Connection marked as disconnected due to device error")
  724. return False
  725. # Retry on exception or corruption error
  726. logger.warning(f"Motion thread: Retrying {gcode}...")
  727. time.sleep(0.1)
  728. # Global motion control thread instance
  729. motion_controller = MotionControlThread()
  730. async def cleanup_pattern_manager():
  731. """Clean up pattern manager resources"""
  732. global progress_update_task, pattern_lock, pause_event
  733. try:
  734. # Signal stop to allow any running pattern to exit gracefully
  735. state.stop_requested = True
  736. # Stop motion control thread
  737. motion_controller.stop()
  738. # Cancel progress update task if running
  739. if progress_update_task and not progress_update_task.done():
  740. try:
  741. progress_update_task.cancel()
  742. # Wait for task to actually cancel
  743. try:
  744. await progress_update_task
  745. except asyncio.CancelledError:
  746. pass
  747. except Exception as e:
  748. logger.error(f"Error cancelling progress update task: {e}")
  749. # Clean up pattern lock - wait for it to be released naturally, don't force release
  750. # Force releasing an asyncio.Lock can corrupt internal state if held by another coroutine
  751. current_lock = pattern_lock
  752. if current_lock and current_lock.locked():
  753. logger.info("Pattern lock is held, waiting for release (max 5s)...")
  754. try:
  755. # Wait with timeout for the lock to become available
  756. # Use wait_for for Python 3.9 compatibility (asyncio.timeout is 3.11+)
  757. async def acquire_lock():
  758. async with current_lock:
  759. pass # Lock acquired means previous holder released it
  760. await asyncio.wait_for(acquire_lock(), timeout=5.0)
  761. logger.info("Pattern lock released normally")
  762. except asyncio.TimeoutError:
  763. logger.warning("Timed out waiting for pattern lock - creating fresh lock")
  764. except Exception as e:
  765. logger.error(f"Error waiting for pattern lock: {e}")
  766. # Clean up pause event - wake up any waiting tasks, then create fresh event
  767. current_event = pause_event
  768. if current_event:
  769. try:
  770. current_event.set() # Wake up any waiting tasks
  771. except Exception as e:
  772. logger.error(f"Error setting pause event: {e}")
  773. # Clean up pause condition from state
  774. if state.pause_condition:
  775. try:
  776. with state.pause_condition:
  777. state.pause_condition.notify_all()
  778. state.pause_condition = threading.Condition()
  779. except Exception as e:
  780. logger.error(f"Error cleaning up pause condition: {e}")
  781. # Clear all state variables
  782. state.current_playing_file = None
  783. state.execution_progress = 0
  784. state.is_running = False
  785. state.pause_requested = False
  786. state.stop_requested = True
  787. state.is_clearing = False
  788. # Reset machine position
  789. await connection_manager.update_machine_position()
  790. logger.info("Pattern manager resources cleaned up")
  791. except Exception as e:
  792. logger.error(f"Error during pattern manager cleanup: {e}")
  793. finally:
  794. # Reset to fresh instances instead of None to allow continued operation
  795. progress_update_task = None
  796. pattern_lock = asyncio.Lock() # Fresh lock instead of None
  797. pause_event = asyncio.Event() # Fresh event instead of None
  798. pause_event.set() # Initially not paused
  799. def list_theta_rho_files():
  800. files = []
  801. for root, dirs, filenames in os.walk(THETA_RHO_DIR):
  802. # Skip cached_images directories to avoid scanning thousands of WebP files
  803. if 'cached_images' in dirs:
  804. dirs.remove('cached_images')
  805. # Filter .thr files during traversal for better performance
  806. thr_files = [f for f in filenames if f.endswith('.thr')]
  807. for file in thr_files:
  808. relative_path = os.path.relpath(os.path.join(root, file), THETA_RHO_DIR)
  809. # Normalize path separators to always use forward slashes for consistency across platforms
  810. relative_path = relative_path.replace(os.sep, '/')
  811. files.append(relative_path)
  812. logger.debug(f"Found {len(files)} theta-rho files")
  813. return files
  814. def parse_theta_rho_file(file_path):
  815. """Parse a theta-rho file and return a list of (theta, rho) pairs."""
  816. coordinates = []
  817. try:
  818. logger.debug(f"Parsing theta-rho file: {file_path}")
  819. with open(file_path, 'r', encoding='utf-8') as file:
  820. for line in file:
  821. line = line.strip()
  822. if not line or line.startswith("#"):
  823. continue
  824. try:
  825. theta, rho = map(float, line.split())
  826. coordinates.append((theta, rho))
  827. except ValueError:
  828. logger.warning(f"Skipping invalid line: {line}")
  829. continue
  830. except Exception as e:
  831. logger.error(f"Error reading file: {e}")
  832. return coordinates
  833. logger.debug(f"Parsed {len(coordinates)} coordinates from {file_path}")
  834. return coordinates
  835. def get_first_rho_from_cache(file_path, cache_data=None):
  836. """Get the first rho value from cached metadata, falling back to file parsing if needed.
  837. Args:
  838. file_path: Path to the pattern file
  839. cache_data: Optional pre-loaded cache data dict to avoid repeated disk I/O
  840. """
  841. try:
  842. # Import cache_manager locally to avoid circular import
  843. from modules.core import cache_manager
  844. # Try to get from metadata cache first
  845. # Use relative path from THETA_RHO_DIR to match cache keys (which include subdirectories)
  846. file_name = os.path.relpath(file_path, THETA_RHO_DIR)
  847. # Use provided cache_data if available, otherwise load from disk
  848. if cache_data is not None:
  849. # Extract metadata directly from provided cache
  850. data_section = cache_data.get('data', {})
  851. if file_name in data_section:
  852. cached_entry = data_section[file_name]
  853. metadata = cached_entry.get('metadata')
  854. # When cache_data is provided, trust it without checking mtime
  855. # This significantly speeds up bulk operations (playlists with 1000+ patterns)
  856. # by avoiding 1000+ os.path.getmtime() calls on slow storage (e.g., Pi SD cards)
  857. if metadata and 'first_coordinate' in metadata:
  858. return metadata['first_coordinate']['y']
  859. else:
  860. # Fall back to loading cache from disk (original behavior)
  861. metadata = cache_manager.get_pattern_metadata(file_name)
  862. if metadata and 'first_coordinate' in metadata:
  863. # In the cache, 'x' is theta and 'y' is rho
  864. return metadata['first_coordinate']['y']
  865. # Fallback to parsing the file if not in cache
  866. logger.debug(f"Metadata not cached for {file_name}, parsing file")
  867. coordinates = parse_theta_rho_file(file_path)
  868. if coordinates:
  869. return coordinates[0][1] # Return rho value
  870. return None
  871. except Exception as e:
  872. logger.warning(f"Error getting first rho from cache for {file_path}: {str(e)}")
  873. return None
  874. def get_clear_pattern_file(clear_pattern_mode, path=None, cache_data=None):
  875. """Return a .thr file path based on pattern_name and table type.
  876. Args:
  877. clear_pattern_mode: The clear pattern mode to use
  878. path: Optional path to the pattern file for adaptive mode
  879. cache_data: Optional pre-loaded cache data dict to avoid repeated disk I/O
  880. """
  881. if not clear_pattern_mode or clear_pattern_mode == 'none':
  882. return
  883. # Define patterns for each table type
  884. clear_patterns = {
  885. 'dune_weaver': {
  886. 'clear_from_out': './patterns/clear_from_out.thr',
  887. 'clear_from_in': './patterns/clear_from_in.thr',
  888. 'clear_sideway': './patterns/clear_sideway.thr'
  889. },
  890. 'dune_weaver_mini': {
  891. 'clear_from_out': './patterns/clear_from_out_mini.thr',
  892. 'clear_from_in': './patterns/clear_from_in_mini.thr',
  893. 'clear_sideway': './patterns/clear_sideway_mini.thr'
  894. },
  895. 'dune_weaver_mini_pro': {
  896. 'clear_from_out': './patterns/clear_from_out_mini.thr',
  897. 'clear_from_in': './patterns/clear_from_in_mini.thr',
  898. 'clear_sideway': './patterns/clear_sideway_mini.thr'
  899. },
  900. 'dune_weaver_pro': {
  901. 'clear_from_out': './patterns/clear_from_out_pro.thr',
  902. 'clear_from_out_Ultra': './patterns/clear_from_out_Ultra.thr',
  903. 'clear_from_in': './patterns/clear_from_in_pro.thr',
  904. 'clear_from_in_Ultra': './patterns/clear_from_in_Ultra.thr',
  905. 'clear_sideway': './patterns/clear_sideway_pro.thr'
  906. }
  907. }
  908. # Get patterns for current table type, fallback to standard patterns if type not found
  909. table_patterns = clear_patterns.get(state.table_type, clear_patterns['dune_weaver'])
  910. # Check for custom patterns first
  911. if state.custom_clear_from_out and clear_pattern_mode in ['clear_from_out', 'adaptive']:
  912. if clear_pattern_mode == 'adaptive':
  913. # For adaptive mode, use cached metadata to check first rho
  914. if path:
  915. first_rho = get_first_rho_from_cache(path, cache_data)
  916. if first_rho is not None and first_rho < 0.5:
  917. # Use custom clear_from_out if set
  918. custom_path = os.path.join('./patterns', state.custom_clear_from_out)
  919. if os.path.exists(custom_path):
  920. logger.debug(f"Using custom clear_from_out: {custom_path}")
  921. return custom_path
  922. elif clear_pattern_mode == 'clear_from_out':
  923. custom_path = os.path.join('./patterns', state.custom_clear_from_out)
  924. if os.path.exists(custom_path):
  925. logger.debug(f"Using custom clear_from_out: {custom_path}")
  926. return custom_path
  927. if state.custom_clear_from_in and clear_pattern_mode in ['clear_from_in', 'adaptive']:
  928. if clear_pattern_mode == 'adaptive':
  929. # For adaptive mode, use cached metadata to check first rho
  930. if path:
  931. first_rho = get_first_rho_from_cache(path, cache_data)
  932. if first_rho is not None and first_rho >= 0.5:
  933. # Use custom clear_from_in if set
  934. custom_path = os.path.join('./patterns', state.custom_clear_from_in)
  935. if os.path.exists(custom_path):
  936. logger.debug(f"Using custom clear_from_in: {custom_path}")
  937. return custom_path
  938. elif clear_pattern_mode == 'clear_from_in':
  939. custom_path = os.path.join('./patterns', state.custom_clear_from_in)
  940. if os.path.exists(custom_path):
  941. logger.debug(f"Using custom clear_from_in: {custom_path}")
  942. return custom_path
  943. logger.debug(f"Clear pattern mode: {clear_pattern_mode} for table type: {state.table_type}")
  944. if clear_pattern_mode == "random":
  945. return random.choice(list(table_patterns.values()))
  946. if clear_pattern_mode == 'adaptive':
  947. if not path:
  948. logger.warning("No path provided for adaptive clear pattern")
  949. return random.choice(list(table_patterns.values()))
  950. # Use cached metadata to get first rho value
  951. first_rho = get_first_rho_from_cache(path, cache_data)
  952. if first_rho is None:
  953. logger.warning("Could not determine first rho value for adaptive clear pattern")
  954. return random.choice(list(table_patterns.values()))
  955. if first_rho < 0.5:
  956. return table_patterns['clear_from_out']
  957. else:
  958. return table_patterns['clear_from_in']
  959. else:
  960. if clear_pattern_mode not in table_patterns:
  961. return False
  962. return table_patterns[clear_pattern_mode]
  963. def is_clear_pattern(file_path):
  964. """Check if a file path is a clear pattern file."""
  965. # Get all possible clear pattern files for all table types
  966. clear_patterns = []
  967. for table_type in ['dune_weaver', 'dune_weaver_mini', 'dune_weaver_pro']:
  968. clear_patterns.extend([
  969. f'./patterns/clear_from_out{("_" + table_type.split("_")[-1]) if table_type != "dune_weaver" else ""}.thr',
  970. f'./patterns/clear_from_in{("_" + table_type.split("_")[-1]) if table_type != "dune_weaver" else ""}.thr',
  971. f'./patterns/clear_sideway{("_" + table_type.split("_")[-1]) if table_type != "dune_weaver" else ""}.thr'
  972. ])
  973. # Normalize paths for comparison
  974. normalized_path = os.path.normpath(file_path)
  975. normalized_clear_patterns = [os.path.normpath(p) for p in clear_patterns]
  976. # Check if the file path matches any clear pattern path
  977. return normalized_path in normalized_clear_patterns
  978. async def _execute_pattern_internal(file_path):
  979. """Internal function to execute a pattern file. Must be called with lock already held.
  980. Args:
  981. file_path: Path to the .thr file to execute
  982. Returns:
  983. True if pattern completed successfully, False if stopped/skipped
  984. """
  985. # Run file parsing in thread to avoid blocking the event loop
  986. coordinates = await asyncio.to_thread(parse_theta_rho_file, file_path)
  987. total_coordinates = len(coordinates)
  988. # Cache coordinates in state for frontend preview (avoids re-parsing large files)
  989. state._current_coordinates = coordinates
  990. if total_coordinates < 2:
  991. logger.warning("Not enough coordinates for interpolation")
  992. return False
  993. # Determine if this is a clearing pattern
  994. is_clear_file = is_clear_pattern(file_path)
  995. if is_clear_file:
  996. initial_speed = state.clear_pattern_speed if state.clear_pattern_speed is not None else state.speed
  997. logger.info(f"Running clearing pattern at initial speed {initial_speed}")
  998. else:
  999. logger.info(f"Running normal pattern at initial speed {state.speed}")
  1000. state.execution_progress = (0, total_coordinates, None, 0)
  1001. # stop actions without resetting the playlist, and don't wait for lock (we already have it)
  1002. # Preserve is_clearing flag since stop_actions resets it
  1003. was_clearing = state.is_clearing
  1004. await stop_actions(clear_playlist=False, wait_for_lock=False)
  1005. state.is_clearing = was_clearing
  1006. state.current_playing_file = file_path
  1007. state.stop_requested = False
  1008. # Reset LED idle timeout activity time when pattern starts
  1009. import time as time_module
  1010. state.dw_led_last_activity_time = time_module.time()
  1011. logger.info(f"Starting pattern execution: {file_path}")
  1012. logger.info(f"t: {state.current_theta}, r: {state.current_rho}")
  1013. await reset_theta()
  1014. start_time = time.time()
  1015. total_pause_time = 0 # Track total time spent paused (manual + scheduled)
  1016. if state.led_controller:
  1017. logger.info(f"Setting LED to playing effect: {state.dw_led_playing_effect}")
  1018. await state.led_controller.effect_playing_async(state.dw_led_playing_effect)
  1019. # Cancel idle timeout when playing starts
  1020. idle_timeout_manager.cancel_timeout()
  1021. with tqdm(
  1022. total=total_coordinates,
  1023. unit="coords",
  1024. desc=f"Executing Pattern {file_path}",
  1025. dynamic_ncols=True,
  1026. disable=False,
  1027. mininterval=1.0
  1028. ) as pbar:
  1029. for i, coordinate in enumerate(coordinates):
  1030. theta, rho = coordinate
  1031. if state.stop_requested:
  1032. logger.info("Execution stopped by user")
  1033. if state.led_controller:
  1034. await state.led_controller.effect_idle_async(state.dw_led_idle_effect)
  1035. start_idle_led_timeout()
  1036. break
  1037. if state.skip_requested:
  1038. logger.info("Skipping pattern...")
  1039. await connection_manager.check_idle_async()
  1040. if state.led_controller:
  1041. await state.led_controller.effect_idle_async(state.dw_led_idle_effect)
  1042. start_idle_led_timeout()
  1043. break
  1044. # Wait for resume if paused (manual or scheduled)
  1045. manual_pause = state.pause_requested
  1046. # Only check scheduled pause during pattern if "finish pattern first" is NOT enabled
  1047. scheduled_pause = is_in_scheduled_pause_period() if not state.scheduled_pause_finish_pattern else False
  1048. if manual_pause or scheduled_pause:
  1049. pause_start = time.time() # Track when pause started
  1050. if manual_pause and scheduled_pause:
  1051. logger.info("Execution paused (manual + scheduled pause active)...")
  1052. elif manual_pause:
  1053. logger.info("Execution paused (manual)...")
  1054. else:
  1055. logger.info("Execution paused (scheduled pause period)...")
  1056. # Turn off LED controller if scheduled pause and control_wled is enabled
  1057. if state.scheduled_pause_control_wled and state.led_controller:
  1058. logger.info("Turning off LED lights during Still Sands period")
  1059. await state.led_controller.set_power_async(0)
  1060. # Only show idle effect if NOT in scheduled pause with LED control
  1061. # (manual pause always shows idle effect)
  1062. if state.led_controller and not (scheduled_pause and state.scheduled_pause_control_wled):
  1063. await state.led_controller.effect_idle_async(state.dw_led_idle_effect)
  1064. start_idle_led_timeout()
  1065. # Remember if we turned off LED controller for scheduled pause
  1066. wled_was_off_for_scheduled = scheduled_pause and state.scheduled_pause_control_wled and not manual_pause
  1067. # Wait until both manual pause is released AND we're outside scheduled pause period
  1068. # Also check for stop/skip requests to allow immediate interruption
  1069. interrupted = False
  1070. while state.pause_requested or is_in_scheduled_pause_period():
  1071. # Check for stop/skip first
  1072. if state.stop_requested:
  1073. logger.info("Stop requested during pause, exiting")
  1074. interrupted = True
  1075. break
  1076. if state.skip_requested:
  1077. logger.info("Skip requested during pause, skipping pattern")
  1078. interrupted = True
  1079. break
  1080. if state.pause_requested:
  1081. # For manual pause, wait on multiple events for immediate response
  1082. # Wake on: resume, stop, skip, or timeout (for flag polling fallback)
  1083. pause_event = get_pause_event()
  1084. stop_event = state.get_stop_event()
  1085. skip_event = state.get_skip_event()
  1086. wait_tasks = [asyncio.create_task(pause_event.wait(), name='pause')]
  1087. if stop_event:
  1088. wait_tasks.append(asyncio.create_task(stop_event.wait(), name='stop'))
  1089. if skip_event:
  1090. wait_tasks.append(asyncio.create_task(skip_event.wait(), name='skip'))
  1091. # Add timeout to ensure we periodically check flags even if events aren't set
  1092. # This handles the case where stop is called from sync context (no event loop)
  1093. timeout_task = asyncio.create_task(asyncio.sleep(1.0), name='timeout')
  1094. wait_tasks.append(timeout_task)
  1095. try:
  1096. done, pending = await asyncio.wait(
  1097. wait_tasks, return_when=asyncio.FIRST_COMPLETED
  1098. )
  1099. finally:
  1100. for task in pending:
  1101. task.cancel()
  1102. for task in pending:
  1103. try:
  1104. await task
  1105. except asyncio.CancelledError:
  1106. pass
  1107. else:
  1108. # For scheduled pause, use wait_for_interrupt for instant response
  1109. result = await state.wait_for_interrupt(timeout=1.0)
  1110. if result in ('stopped', 'skipped'):
  1111. interrupted = True
  1112. break
  1113. total_pause_time += time.time() - pause_start # Add pause duration
  1114. if interrupted:
  1115. # Exit the coordinate loop if we were interrupted
  1116. break
  1117. logger.info("Execution resumed...")
  1118. if state.led_controller:
  1119. # Turn LED controller back on if it was turned off for scheduled pause
  1120. if wled_was_off_for_scheduled:
  1121. logger.info("Turning LED lights back on as Still Sands period ended")
  1122. await state.led_controller.set_power_async(1)
  1123. # CRITICAL: Give LED controller time to fully power on before sending more commands
  1124. # Without this delay, rapid-fire requests can crash controllers on resource-constrained Pis
  1125. await asyncio.sleep(0.5)
  1126. await state.led_controller.effect_playing_async(state.dw_led_playing_effect)
  1127. # Cancel idle timeout when resuming from pause
  1128. idle_timeout_manager.cancel_timeout()
  1129. # Dynamically determine the speed for each movement
  1130. # Use clear_pattern_speed if it's set and this is a clear file, otherwise use state.speed
  1131. if is_clear_file and state.clear_pattern_speed is not None:
  1132. current_speed = state.clear_pattern_speed
  1133. else:
  1134. current_speed = state.speed
  1135. await move_polar(theta, rho, current_speed)
  1136. # Update progress for all coordinates including the first one
  1137. pbar.update(1)
  1138. elapsed_time = time.time() - start_time
  1139. estimated_remaining_time = (total_coordinates - (i + 1)) / pbar.format_dict['rate'] if pbar.format_dict['rate'] and total_coordinates else 0
  1140. state.execution_progress = (i + 1, total_coordinates, estimated_remaining_time, elapsed_time)
  1141. # Add a small delay to allow other async operations
  1142. await asyncio.sleep(0.001)
  1143. # Update progress one last time to show 100%
  1144. elapsed_time = time.time() - start_time
  1145. actual_execution_time = elapsed_time - total_pause_time
  1146. state.execution_progress = (total_coordinates, total_coordinates, 0, elapsed_time)
  1147. # Give WebSocket a chance to send the final update
  1148. await asyncio.sleep(0.1)
  1149. # Log execution time (only for completed patterns, not stopped/skipped)
  1150. was_completed = not state.stop_requested and not state.skip_requested
  1151. pattern_name = os.path.basename(file_path)
  1152. effective_speed = state.clear_pattern_speed if (is_clear_file and state.clear_pattern_speed is not None) else state.speed
  1153. log_execution_time(
  1154. pattern_name=pattern_name,
  1155. table_type=state.table_type,
  1156. speed=effective_speed,
  1157. actual_time=actual_execution_time,
  1158. total_coordinates=total_coordinates,
  1159. was_completed=was_completed
  1160. )
  1161. if not state.conn:
  1162. logger.error("Device is not connected. Stopping pattern execution.")
  1163. return False
  1164. await connection_manager.check_idle_async()
  1165. # Set LED back to idle when pattern completes normally (not stopped early)
  1166. if state.led_controller and not state.stop_requested:
  1167. logger.info(f"Setting LED to idle effect: {state.dw_led_idle_effect}")
  1168. await state.led_controller.effect_idle_async(state.dw_led_idle_effect)
  1169. start_idle_led_timeout()
  1170. logger.debug("LED effect set to idle after pattern completion")
  1171. return was_completed
  1172. async def run_theta_rho_file(file_path, is_playlist=False, clear_pattern=None, cache_data=None):
  1173. """Run a theta-rho file with optional pre-execution clear pattern.
  1174. Args:
  1175. file_path: Path to the main .thr file to execute
  1176. is_playlist: True if running as part of a playlist
  1177. clear_pattern: Clear pattern mode ('adaptive', 'clear_from_in', 'clear_from_out', 'none', or None)
  1178. cache_data: Pre-loaded metadata cache for adaptive clear pattern selection
  1179. """
  1180. lock = get_pattern_lock()
  1181. if lock.locked():
  1182. logger.warning("Another pattern is already running. Cannot start a new one.")
  1183. return
  1184. async with lock: # This ensures only one pattern can run at a time
  1185. # Clear any stale pause state from previous playlist
  1186. state.pause_time_remaining = 0
  1187. state.original_pause_time = None
  1188. # Start progress update task only if not part of a playlist
  1189. global progress_update_task
  1190. if not is_playlist and not progress_update_task:
  1191. progress_update_task = asyncio.create_task(broadcast_progress())
  1192. # Run clear pattern first if specified
  1193. if clear_pattern and clear_pattern != 'none':
  1194. clear_file_path = get_clear_pattern_file(clear_pattern, file_path, cache_data)
  1195. if clear_file_path:
  1196. logger.info(f"Running pre-execution clear pattern: {clear_file_path}")
  1197. state.is_clearing = True
  1198. await _execute_pattern_internal(clear_file_path)
  1199. state.is_clearing = False
  1200. # Reset skip flag after clear pattern (if user skipped clear, continue to main)
  1201. state.skip_requested = False
  1202. # Check if stopped during clear pattern
  1203. if state.stop_requested:
  1204. logger.info("Execution stopped during clear pattern")
  1205. if not is_playlist:
  1206. state.current_playing_file = None
  1207. state.execution_progress = None
  1208. return
  1209. # Run the main pattern
  1210. completed = await _execute_pattern_internal(file_path)
  1211. # Only clear state if not part of a playlist
  1212. if not is_playlist:
  1213. state.current_playing_file = None
  1214. state.execution_progress = None
  1215. logger.info("Pattern execution completed and state cleared")
  1216. # Only cancel progress update task if not part of a playlist
  1217. if progress_update_task:
  1218. progress_update_task.cancel()
  1219. try:
  1220. await progress_update_task
  1221. except asyncio.CancelledError:
  1222. pass
  1223. progress_update_task = None
  1224. else:
  1225. logger.info("Pattern execution completed, maintaining state for playlist")
  1226. async def run_theta_rho_files(file_paths, pause_time=0, clear_pattern=None, run_mode="single", shuffle=False):
  1227. """Run multiple .thr files in sequence with options.
  1228. The playlist now stores only main patterns. Clear patterns are executed dynamically
  1229. before each main pattern based on the clear_pattern option.
  1230. """
  1231. state.stop_requested = False
  1232. # Track whether we actually started executing patterns.
  1233. # If cancelled before execution begins (e.g., by TestClient cleanup),
  1234. # we should NOT clear state that was set by the caller.
  1235. task_started_execution = False
  1236. # Reset LED idle timeout activity time when playlist starts
  1237. import time as time_module
  1238. state.dw_led_last_activity_time = time_module.time()
  1239. # Set initial playlist state only if not already set by caller (playlist_manager).
  1240. # This ensures backward compatibility when this function is called directly.
  1241. if state.playlist_mode is None:
  1242. state.playlist_mode = run_mode
  1243. if state.current_playlist_index is None:
  1244. state.current_playlist_index = 0
  1245. # Start progress update task for the playlist
  1246. global progress_update_task
  1247. if not progress_update_task:
  1248. progress_update_task = asyncio.create_task(broadcast_progress())
  1249. # Shuffle main patterns if requested (before starting)
  1250. if shuffle:
  1251. random.shuffle(file_paths)
  1252. logger.info("Playlist shuffled")
  1253. # Store patterns in state only if not already set by caller.
  1254. # The caller (playlist_manager.run_playlist) sets this before creating the task.
  1255. if state.current_playlist is None:
  1256. state.current_playlist = file_paths
  1257. try:
  1258. while True:
  1259. # Load metadata cache once per playlist iteration (for adaptive clear patterns)
  1260. cache_data = None
  1261. if clear_pattern and clear_pattern in ['adaptive', 'clear_from_in', 'clear_from_out']:
  1262. from modules.core import cache_manager
  1263. cache_data = await asyncio.to_thread(cache_manager.load_metadata_cache)
  1264. logger.info(f"Loaded metadata cache for {len(cache_data.get('data', {}))} patterns")
  1265. # Reset pattern counter at the start of the playlist
  1266. state.patterns_since_last_home = 0
  1267. # Execute main patterns using index-based access
  1268. # This allows the playlist to be reordered during execution
  1269. idx = 0
  1270. while state.current_playlist and idx < len(state.current_playlist):
  1271. state.current_playlist_index = idx
  1272. if state.stop_requested or not state.current_playlist:
  1273. logger.info("Execution stopped")
  1274. return
  1275. # Get the pattern at the current index (may have changed due to reordering)
  1276. file_path = state.current_playlist[idx]
  1277. logger.info(f"Running pattern {idx + 1}/{len(state.current_playlist)}: {file_path}")
  1278. # Mark that we've started actual execution (for cleanup logic)
  1279. task_started_execution = True
  1280. # Clear pause state when starting a new pattern (prevents stale "waiting" UI)
  1281. state.pause_time_remaining = 0
  1282. state.original_pause_time = None
  1283. # Execute the pattern with optional clear pattern
  1284. await run_theta_rho_file(
  1285. file_path,
  1286. is_playlist=True,
  1287. clear_pattern=clear_pattern,
  1288. cache_data=cache_data
  1289. )
  1290. # Increment pattern counter (auto-home check happens after pause time)
  1291. state.patterns_since_last_home += 1
  1292. logger.debug(f"Patterns since last home: {state.patterns_since_last_home}")
  1293. # Check for scheduled pause after pattern completes (when "finish pattern first" is enabled)
  1294. if state.scheduled_pause_finish_pattern and is_in_scheduled_pause_period() and not state.stop_requested and not state.skip_requested:
  1295. logger.info("Pattern completed. Entering Still Sands period (finish pattern first mode)...")
  1296. wled_was_off_for_scheduled = False
  1297. if state.scheduled_pause_control_wled and state.led_controller:
  1298. logger.info("Turning off LED lights during Still Sands period")
  1299. await state.led_controller.set_power_async(0)
  1300. wled_was_off_for_scheduled = True
  1301. elif state.led_controller:
  1302. await state.led_controller.effect_idle_async(state.dw_led_idle_effect)
  1303. start_idle_led_timeout()
  1304. # Wait for scheduled pause to end, but allow stop/skip to interrupt
  1305. result = await wait_with_interrupt(
  1306. is_in_scheduled_pause_period,
  1307. check_stop=True,
  1308. check_skip=True,
  1309. )
  1310. if result == 'completed':
  1311. logger.info("Still Sands period ended. Resuming playlist...")
  1312. if state.led_controller:
  1313. if wled_was_off_for_scheduled:
  1314. logger.info("Turning LED lights back on as Still Sands period ended")
  1315. await state.led_controller.set_power_async(1)
  1316. await asyncio.sleep(0.5)
  1317. await state.led_controller.effect_playing_async(state.dw_led_playing_effect)
  1318. idle_timeout_manager.cancel_timeout()
  1319. # Handle pause between patterns
  1320. if state.current_playlist and idx < len(state.current_playlist) - 1 and not state.stop_requested and pause_time > 0 and not state.skip_requested:
  1321. logger.info(f"Pausing for {pause_time} seconds")
  1322. state.original_pause_time = pause_time
  1323. pause_start = time.time()
  1324. while time.time() - pause_start < pause_time:
  1325. state.pause_time_remaining = pause_start + pause_time - time.time()
  1326. if state.skip_requested:
  1327. logger.info("Pause interrupted by skip request")
  1328. break
  1329. await asyncio.sleep(1)
  1330. # Clear both pause state vars immediately (so UI updates right away)
  1331. state.pause_time_remaining = 0
  1332. state.original_pause_time = None
  1333. # Auto-home after pause time, before next clear pattern starts
  1334. # Only home if there's a next pattern and we haven't been stopped
  1335. if (state.auto_home_enabled and
  1336. state.patterns_since_last_home >= state.auto_home_after_patterns and
  1337. state.current_playlist and idx < len(state.current_playlist) - 1 and
  1338. not state.stop_requested):
  1339. logger.info(f"Auto-homing triggered after {state.patterns_since_last_home} patterns (before next clear pattern)")
  1340. try:
  1341. success = await asyncio.to_thread(connection_manager.home)
  1342. if success:
  1343. logger.info("Auto-homing completed successfully")
  1344. state.patterns_since_last_home = 0
  1345. else:
  1346. logger.warning("Auto-homing failed, continuing with playlist")
  1347. except Exception as e:
  1348. logger.error(f"Error during auto-homing: {e}")
  1349. state.skip_requested = False
  1350. idx += 1
  1351. if run_mode == "indefinite":
  1352. logger.info("Playlist completed. Restarting as per 'indefinite' run mode")
  1353. if pause_time > 0:
  1354. pause_start = time.time()
  1355. while time.time() - pause_start < pause_time:
  1356. state.pause_time_remaining = pause_start + pause_time - time.time()
  1357. if state.skip_requested:
  1358. logger.info("Pause interrupted by skip request")
  1359. break
  1360. await asyncio.sleep(1)
  1361. # Clear both pause state vars immediately (so UI updates right away)
  1362. state.pause_time_remaining = 0
  1363. state.original_pause_time = None
  1364. continue
  1365. else:
  1366. logger.info("Playlist completed")
  1367. break
  1368. except asyncio.CancelledError:
  1369. # Task was cancelled externally (e.g., by TestClient cleanup, or explicit cancellation).
  1370. # Do NOT clear playlist state - preserve what the caller set.
  1371. logger.info("Playlist task was cancelled externally, preserving state")
  1372. if progress_update_task:
  1373. progress_update_task.cancel()
  1374. try:
  1375. await progress_update_task
  1376. except asyncio.CancelledError:
  1377. pass
  1378. progress_update_task = None
  1379. raise # Re-raise to signal cancellation
  1380. finally:
  1381. if progress_update_task:
  1382. progress_update_task.cancel()
  1383. try:
  1384. await progress_update_task
  1385. except asyncio.CancelledError:
  1386. pass
  1387. progress_update_task = None
  1388. # Check if we're exiting due to CancelledError - if so, don't clear state.
  1389. # State should only be cleared when:
  1390. # 1. Task completed normally (all patterns executed)
  1391. # 2. Task was stopped by user request (stop_requested)
  1392. # NOT when task was cancelled externally (CancelledError)
  1393. import sys
  1394. exc_type = sys.exc_info()[0]
  1395. if exc_type is asyncio.CancelledError:
  1396. logger.info("Task exiting due to cancellation, state preserved for caller")
  1397. else:
  1398. # Normal completion or user-requested stop - clear state
  1399. state.current_playing_file = None
  1400. state.execution_progress = None
  1401. state.current_playlist = None
  1402. state.current_playlist_index = None
  1403. state.playlist_mode = None
  1404. state.pause_time_remaining = 0
  1405. if state.led_controller:
  1406. await state.led_controller.effect_idle_async(state.dw_led_idle_effect)
  1407. start_idle_led_timeout()
  1408. logger.info("All requested patterns completed (or stopped) and state cleared")
  1409. async def stop_actions(clear_playlist = True, wait_for_lock = True):
  1410. """Stop all current actions and wait for pattern to fully release.
  1411. Args:
  1412. clear_playlist: Whether to clear playlist state
  1413. wait_for_lock: Whether to wait for pattern_lock to be released. Set to False when
  1414. called from within pattern execution to avoid deadlock.
  1415. Returns:
  1416. True if stopped cleanly, False if timed out waiting for pattern lock
  1417. """
  1418. timed_out = False
  1419. try:
  1420. with state.pause_condition:
  1421. state.pause_requested = False
  1422. state.stop_requested = True
  1423. state.is_clearing = False
  1424. # Always clear pause time between patterns on stop
  1425. state.pause_time_remaining = 0
  1426. state.original_pause_time = None
  1427. if clear_playlist:
  1428. # Clear playlist state
  1429. state.current_playlist = None
  1430. state.current_playlist_index = None
  1431. state.playlist_mode = None
  1432. # Cancel progress update task if we're clearing the playlist
  1433. global progress_update_task
  1434. if progress_update_task and not progress_update_task.done():
  1435. progress_update_task.cancel()
  1436. # Cancel the playlist task itself (late import to avoid circular dependency)
  1437. from modules.core import playlist_manager
  1438. await playlist_manager.cancel_current_playlist()
  1439. state.pause_condition.notify_all()
  1440. # Also set the pause event to wake up any paused patterns
  1441. get_pause_event().set()
  1442. # Send stop command to motion thread to clear its queue
  1443. if motion_controller.running:
  1444. motion_controller.command_queue.put(MotionCommand('stop'))
  1445. # Wait for the pattern lock to be released before continuing
  1446. # This ensures that when stop_actions completes, the pattern has fully stopped
  1447. # Skip this if called from within pattern execution to avoid deadlock
  1448. lock = get_pattern_lock()
  1449. if wait_for_lock and lock.locked():
  1450. logger.info("Waiting for pattern to fully stop...")
  1451. # Use a timeout to prevent hanging forever
  1452. # Use wait_for for Python 3.9 compatibility (asyncio.timeout is 3.11+)
  1453. try:
  1454. async def acquire_stop_lock():
  1455. async with lock:
  1456. logger.info("Pattern lock acquired - pattern has fully stopped")
  1457. await asyncio.wait_for(acquire_stop_lock(), timeout=10.0)
  1458. except asyncio.TimeoutError:
  1459. logger.warning("Timeout waiting for pattern to stop - forcing cleanup")
  1460. timed_out = True
  1461. # Force cleanup of state even if pattern didn't release lock gracefully
  1462. state.current_playing_file = None
  1463. state.execution_progress = None
  1464. state.is_running = False
  1465. # Clear current playing file only when clearing the entire playlist.
  1466. # When clear_playlist=False (called from within pattern execution), the caller
  1467. # will set current_playing_file to the new pattern immediately after.
  1468. if clear_playlist:
  1469. state.current_playing_file = None
  1470. state.execution_progress = None
  1471. # Clear stop_requested now that the pattern has stopped - this allows
  1472. # check_idle_async to work (it exits early if stop_requested is True)
  1473. state.stop_requested = False
  1474. # Wait for hardware to reach idle state before returning
  1475. # This ensures the machine has physically stopped moving
  1476. if not timed_out:
  1477. idle = await connection_manager.check_idle_async(timeout=30.0)
  1478. if not idle:
  1479. logger.warning("Machine did not reach idle after stop")
  1480. # Call async function directly since we're in async context
  1481. await connection_manager.update_machine_position()
  1482. return not timed_out
  1483. except Exception as e:
  1484. logger.error(f"Error during stop_actions: {e}")
  1485. # Force cleanup state on error
  1486. state.current_playing_file = None
  1487. state.execution_progress = None
  1488. state.is_running = False
  1489. # Ensure we still update machine position even if there's an error
  1490. try:
  1491. await connection_manager.update_machine_position()
  1492. except Exception as update_err:
  1493. logger.error(f"Error updating machine position on error: {update_err}")
  1494. return False
  1495. async def move_polar(theta, rho, speed=None):
  1496. """
  1497. Queue a motion command to be executed in the dedicated motion control thread.
  1498. This makes motion control non-blocking for API endpoints.
  1499. Args:
  1500. theta (float): Target theta coordinate
  1501. rho (float): Target rho coordinate
  1502. speed (int, optional): Speed override. If None, uses state.speed
  1503. """
  1504. # Note: stop_requested is cleared once at pattern start (execute_theta_rho_file line 890)
  1505. # Don't clear it here on every coordinate - causes performance issues with event system
  1506. # Ensure motion control thread is running
  1507. if not motion_controller.running:
  1508. motion_controller.start()
  1509. # Create future for async/await pattern
  1510. loop = asyncio.get_event_loop()
  1511. future = loop.create_future()
  1512. # Create and queue motion command
  1513. command = MotionCommand(
  1514. command_type='move',
  1515. theta=theta,
  1516. rho=rho,
  1517. speed=speed,
  1518. future=future
  1519. )
  1520. motion_controller.command_queue.put(command)
  1521. logger.debug(f"Queued motion command: theta={theta}, rho={rho}, speed={speed}")
  1522. # Wait for command completion
  1523. await future
  1524. def pause_execution():
  1525. """Pause pattern execution using asyncio Event."""
  1526. logger.info("Pausing pattern execution")
  1527. state.pause_requested = True
  1528. get_pause_event().clear() # Clear the event to pause execution
  1529. return True
  1530. def resume_execution():
  1531. """Resume pattern execution using asyncio Event."""
  1532. logger.info("Resuming pattern execution")
  1533. state.pause_requested = False
  1534. get_pause_event().set() # Set the event to resume execution
  1535. return True
  1536. async def reset_theta():
  1537. """
  1538. Reset theta to [0, 2π) range and hard reset machine position using $Bye.
  1539. $Bye sends a soft reset to FluidNC which resets the controller and clears
  1540. all position counters to 0. This is more reliable than G92 which only sets
  1541. a work coordinate offset without changing the actual machine position (MPos).
  1542. IMPORTANT: We wait for machine to be idle before sending $Bye to avoid
  1543. error:25 ("Feed rate not specified in block") which can occur if the
  1544. controller is still processing commands when reset is triggered.
  1545. """
  1546. logger.info('Resetting Theta')
  1547. # Wait for machine to be idle before reset to prevent error:25
  1548. if state.conn and state.conn.is_connected():
  1549. logger.info("Waiting for machine to be idle before reset...")
  1550. idle = await connection_manager.check_idle_async(timeout=30)
  1551. if not idle:
  1552. logger.warning("Machine not idle after 30s, proceeding with reset anyway")
  1553. state.current_theta = state.current_theta % (2 * pi)
  1554. # Hard reset machine position using $Bye via connection_manager
  1555. success = await connection_manager.perform_soft_reset()
  1556. if not success:
  1557. logger.error("Soft reset failed - theta reset may be unreliable")
  1558. def set_speed(new_speed):
  1559. state.speed = new_speed
  1560. logger.info(f'Set new state.speed {new_speed}')
  1561. def get_status():
  1562. """Get the current status of pattern execution."""
  1563. status = {
  1564. "current_file": state.current_playing_file,
  1565. "is_paused": state.pause_requested or is_in_scheduled_pause_period(),
  1566. "manual_pause": state.pause_requested,
  1567. "scheduled_pause": is_in_scheduled_pause_period(),
  1568. "is_running": bool(state.current_playing_file and not state.stop_requested),
  1569. "is_homing": state.is_homing,
  1570. "sensor_homing_failed": state.sensor_homing_failed,
  1571. "is_clearing": state.is_clearing,
  1572. "progress": None,
  1573. "playlist": None,
  1574. "speed": state.speed,
  1575. "pause_time_remaining": state.pause_time_remaining,
  1576. "original_pause_time": getattr(state, 'original_pause_time', None),
  1577. "connection_status": state.conn.is_connected() if state.conn else False,
  1578. "current_theta": state.current_theta,
  1579. "current_rho": state.current_rho
  1580. }
  1581. # Add playlist information if available
  1582. if state.current_playlist and state.current_playlist_index is not None:
  1583. # When a clear pattern is running, the "next" pattern is the current main pattern
  1584. # (since the clear pattern runs before the main pattern at current_playlist_index)
  1585. if state.is_clearing:
  1586. next_file = state.current_playlist[state.current_playlist_index]
  1587. else:
  1588. next_index = state.current_playlist_index + 1
  1589. next_file = state.current_playlist[next_index] if next_index < len(state.current_playlist) else None
  1590. status["playlist"] = {
  1591. "current_index": state.current_playlist_index,
  1592. "total_files": len(state.current_playlist),
  1593. "mode": state.playlist_mode,
  1594. "next_file": next_file,
  1595. "files": state.current_playlist,
  1596. "name": state.current_playlist_name
  1597. }
  1598. if state.execution_progress:
  1599. current, total, remaining_time, elapsed_time = state.execution_progress
  1600. status["progress"] = {
  1601. "current": current,
  1602. "total": total,
  1603. "remaining_time": remaining_time,
  1604. "elapsed_time": elapsed_time,
  1605. "percentage": (current / total * 100) if total > 0 else 0
  1606. }
  1607. # Add historical execution time if available for this pattern at current speed
  1608. if state.current_playing_file:
  1609. pattern_name = os.path.basename(state.current_playing_file)
  1610. historical_time = get_last_completed_execution_time(pattern_name, state.speed)
  1611. if historical_time:
  1612. status["progress"]["last_completed_time"] = historical_time
  1613. return status
  1614. async def broadcast_progress():
  1615. """Background task to broadcast progress updates."""
  1616. from main import broadcast_status_update
  1617. while True:
  1618. # Send status updates regardless of pattern_lock state
  1619. status = get_status()
  1620. # Use the existing broadcast function from main.py
  1621. await broadcast_status_update(status)
  1622. # Check if we should stop broadcasting
  1623. if not state.current_playlist:
  1624. # If no playlist, only stop if no pattern is being executed
  1625. if not get_pattern_lock().locked():
  1626. logger.info("No playlist or pattern running, stopping broadcast")
  1627. break
  1628. # Wait before next update
  1629. await asyncio.sleep(1)