tracker

33.0 KB

1. // lib/jobs.hl — THE BACKGROUND JOBS (mission 028: out of project.hl, unchanged): the daily TMDB sync run, the adult and kind
2. // backfills, the details repair, the credits job, the duplicate merge, the short-id backfill and the collection seed — each with
3. // its queue, its pause, its log lines, and the clocks that drive them (which job runs when, which one waits for which). The work
4. // of ONE step is the topic's own (sync.hl, backfill.hl, details.hl, credits.hl, merge.hl, shortids.hl, collections.hl).
5. // A CLASS, not statics: its state (queues, positions, totals) is rewritten on every step, and a static cannot be reassigned —
6. // project.hl makes the one instance (`jobs = new Jobs()`) after the server, as these lines ran there before; constructing it
7. // starts the clocks and logs which jobs are off.
8. import { env } from 'hl:proc'
9. import { readFile, exists } from 'hl:fs'
10. import { now, timestamp, every } from 'hl:time'
11. import { syncEnabled, pauseMsAfter, msPerRequest } from './tmdb.hl'
12. import { syncShow, syncShowDelta, persistSync } from './sync.hl'
13. import { emptyTotals, addTotals, totalsLine } from './sync-helpers.hl'
14. import { planStart, planStep, planIds, planLine, markSynced, isFollowedInRun } from './dailysync.hl'
15. import { adultBackfillQueue, backfillTitle, kindBackfillQueue, kindTitle } from './backfill.hl'
16. import { detailsRepairQueue, completeStep, persistDetails, applySynced } from './details.hl'
17. import { persistCredits, creditsQueue, creditsStep } from './credits.hl'
18. import { allFollowedShowIds } from './follows.hl'
19. import { refreshCatalogShow } from './catalog.hl'
20. import { searchTitleChanged, searchTitleRenamed } from './search.hl'
21. import { mergePrepare, mergeStep, mergeStats, mergeLine, persistMerge } from './merge.hl'
22. import { shortIdStep, shortIdStats, persistShortIds, shortIdsLeft } from './shortids.hl'
23. import { seedPrepare, seedStep, seedLine, seedPersist } from './collections.hl'
25. // ---- THE DAILY TMDB SYNC (tracker.worldapi.org#9, sync.hl; tracker#18, dailysync.hl) -------------------------
26. // Once a day at TRACKER_SYNC_HOUR (UTC, default 4 — hl:time has no time zones; the container runs UTC). hl:web serves ONE
27. // request at a time, so the run never takes the server for itself: a 1-second clock does ONE step per tick and returns — the
28. // requests that queued meanwhile are served between two ticks, and a tick never starts inside a page render.
29. // tracker#18: the run first asks TMDB's and TVmaze's CHANGE LISTS since the last run (dailysync.hl — one list page per step)
30. // and then syncs only the titles of ours that changed, followed ones first — every title stays current, not only the followed
31. // ones. FULL WALK (every followed show, as before #18) on the first run, after a gap > 14 days, or when a change list fails.
32. // A title's step: sync.hl syncShow (1–3 TMDB requests + maybe its poster, TVmaze) + details.hl applySynced (the record's
33. // title, overview, tagline … and cast/crew from the same details answer). After a step that made n requests the next waits
34. // n × 260 ms (TMDB: ≤ 40 requests / 10 s) + 550 ms per TVmaze request (≤ 20 / 10 s, tracker.worldapi.org#12).
35. // The finished run stores its day (dailysync.hl markSynced). Log: "tmdb sync: start, …", the change lists ("tmdb sync:
36. // changes since …"), errors, slow steps, "tmdb sync done: mode=… changeRequests=… <totals>".
37. // TRACKER_SYNC=0 switches it off; without TMDB_READ_TOKEN it is off too (logged once).
38. syncHour = env('TRACKER_SYNC_HOUR') != null ? toNumber(env('TRACKER_SYNC_HOUR')) : 4
39. syncOn = env('TRACKER_SYNC') != '0' && syncEnabled
40. syncQueue = []
41. syncPlanning = false
42. syncMode = ''
43. syncMark = false
44. syncChangeRequests = 0
45. syncAt = 0
46. syncDay = ''
47. syncNextAt = 0
48. syncTotals = emptyTotals()
49. syncFinish = () => {
50. persistDetails()
51. if (syncMark) { markSynced(syncDay) }
52. console.log('tmdb sync done: mode=' + syncMode + ' changeRequests=' + syncChangeRequests + ' ' + totalsLine(syncTotals) + ' seconds=' + ((now() - syncAt) / 1000))
53. return null
54. }
55. syncTick = () => {
56. t = now()
57. if (syncQueue.length == 0 && !syncPlanning) {
58. stamp = timestamp(t)
59. day = stamp.slice(0, 10)
60. if (toNumber(stamp.slice(11, 13)) != syncHour || day == syncDay) { return null }
61. syncDay = day
62. syncAt = t
63. syncNextAt = 0
64. syncTotals = emptyTotals()
65. syncChangeRequests = 0
66. p = planStart(day, null)
67. syncMode = p.mode
68. syncMark = true
69. if (p.mode == 'delta') {
70. syncPlanning = true
71. console.log('tmdb sync: start, changes ' + p.why)
72. return null
73. }
74. syncQueue = allFollowedShowIds()
75. console.log('tmdb sync: start, ' + syncQueue.length + ' followed shows, full walk (' + p.why + ')')
76. if (syncQueue.length == 0) { syncFinish() }
77. return null
78. }
79. if (t < syncNextAt) { return null }
80. if (syncPlanning) {
81. ps = planStep()
82. syncChangeRequests = syncChangeRequests + ps.requests + ps.tvmazeRequests
83. syncTotals.requests = syncTotals.requests + ps.requests
84. syncTotals.tvmazeRequests = syncTotals.tvmazeRequests + ps.tvmazeRequests
85. syncNextAt = now() + pauseMsAfter(ps)
86. if (!ps.done) { return null }
87. syncPlanning = false
88. console.log('tmdb sync: ' + planLine())
89. if (ps.failed != '') {
90. // a change list could not be read: the old full walk, and the day is not advanced (the next run asks again)
91. syncMode = 'full'
92. syncMark = false
93. syncQueue = allFollowedShowIds()
94. console.log('tmdb sync: change list failed, full walk of ' + syncQueue.length + ' followed shows instead')
95. } else {
96. syncQueue = planIds()
97. }
98. if (syncQueue.length == 0) { syncFinish() }
99. return null
100. }
101. id = syncQueue[0]
102. rest = []
103. let i = 1
104. while (i < syncQueue.length) { rest.push(syncQueue[i]) i = i + 1 }
105. syncQueue = rest
106. // tracker#18: in a delta run a title nobody follows gets the light step (changed seasons, no TVmaze)
107. r = syncMode == 'delta' && !isFollowedInRun(id) ? syncShowDelta(id) : syncShow(id)
108. // tracker#18 + #28: the record's own fields (title, overview, tagline …) and the cast + crew from the same details answer
109. if (r.details != null) {
110. c = applySynced(id, r.details)
111. if (c.error != '') { console.log('tmdb sync: ' + c.error) }
112. if (c.renamed) { searchTitleRenamed(id) }
113. }
114. // the public lists (catalog.hl, tracker.worldapi.org#13): this show's place by its newest released episode; the sync
115. // also brings TMDB's adult flag (mission 054) → the search's view of it too
116. refreshCatalogShow(id)
117. searchTitleChanged(id)
118. took = now() - t
119. syncTotals = addTotals(syncTotals, r)
120. if (r.error != '') { console.log('tmdb sync: ' + r.error) }
121. // a step blocks every request meanwhile (Saturday Night Live, 53 seasons, 4 requests: ~1.7 s) — say so in the log
122. if (took > 1500) { console.log('tmdb sync: slow step, show ' + id + ', ' + r.requests + ' requests, ' + took + ' ms') }
123. syncNextAt = now() + pauseMsAfter(r)
124. if (syncQueue.length > 0 && syncTotals.shows % 10 == 0) { persistDetails() }
125. if (syncQueue.length == 0) { syncFinish() }
126. return null
127. }
129. // ---- THE ADULT-FLAG BACKFILL (mission 054, backfill.hl backfillTitle) -----------------------------------
130. // The public lists and the search show only titles TMDB says are not adult (shows.hl isPublicTitle; unknown = hidden). At
131. // start the app collects every title whose flag is still unknown and asks TMDB for ONE title per step — like the daily
132. // sync, on the same clock, so requests queued meanwhile are served between two steps: one details request (adult,
133. // poster_path, external ids) + the poster file when missing, then a pause of 260 ms per TMDB API request (≤ 40 / 10 s;
134. // the poster comes from TMDB's image host, not the API, and is not counted). Each answered title goes into the lists
135. // (catalog.hl refreshCatalogShow) and the search at once. RESUMES after a restart: the queue is built from what is
136. // still unknown. Stops when the queue is empty; titles TMDB answers 404 for are marked (`adultCheck`) and stay hidden; a
137. // passing failure (no answer, 429, 5xx) goes to the back of the queue, at most 2 more tries per start.
138. // While the daily sync runs, the backfill waits. Log: start, every TRACKER_BACKFILL_LOG_EVERY (500) titles, done.
139. // TRACKER_BACKFILL=0 switches it off; without TMDB_READ_TOKEN it is off too.
140. backfillOn = env('TRACKER_BACKFILL') != '0' && syncEnabled
141. backfillLogEvery = env('TRACKER_BACKFILL_LOG_EVERY') != null ? toNumber(env('TRACKER_BACKFILL_LOG_EVERY')) : 500
142. backfillQueue = null
143. backfillPos = 0
144. backfillDone = false
145. backfillAt = 0
146. backfillNextAt = 0
147. backfillTries = {}
148. backfillTotals = { titles = 0 adult = 0 notAdult = 0 unknown = 0 posters = 0 ids = 0 requests = 0 errors = 0 retries = 0 }
149. backfillLine = () => {
150. b = backfillTotals
151. return b.titles + '/' + backfillQueue.length + ' titles, adult=' + b.adult + ' notAdult=' + b.notAdult + ' unknown=' + b.unknown + ' posters=' + b.posters
152. + ' ids=' + b.ids + ' requests=' + b.requests + ' errors=' + b.errors + ' retries=' + b.retries + ' seconds=' + ((now() - backfillAt) / 1000)
153. }
154. backfillTick = () => {
155. if (backfillDone) { return null }
156. t = now()
157. if (backfillQueue == null) {
158. backfillQueue = adultBackfillQueue()
159. backfillAt = t
160. console.log('adult backfill: start, ' + backfillQueue.length + ' titles without an adult flag (' + (now() - t) + ' ms to find them)')
161. if (backfillQueue.length == 0) { backfillDone = true console.log('adult backfill: nothing to do') }
162. return null
163. }
164. if (t < backfillNextAt) { return null }
165. id = backfillQueue[backfillPos]
166. backfillPos = backfillPos + 1
167. r = backfillTitle(id)
168. if (r.retry && (backfillTries[id] == null || backfillTries[id] < 2)) {
169. backfillTries[id] = backfillTries[id] == null ? 1 : backfillTries[id] + 1
170. q = backfillQueue
171. q.push(id)
172. backfillQueue = q
173. backfillTotals.retries = backfillTotals.retries + 1
174. } else {
175. backfillTotals.titles = backfillTotals.titles + 1
176. if (r.adult == true) { backfillTotals.adult = backfillTotals.adult + 1 } else if (r.adult == false) { backfillTotals.notAdult = backfillTotals.notAdult + 1 } else { backfillTotals.unknown = backfillTotals.unknown + 1 }
177. }
178. backfillTotals.posters = backfillTotals.posters + r.posters
179. backfillTotals.ids = backfillTotals.ids + r.ids
180. backfillTotals.requests = backfillTotals.requests + r.requests
181. backfillTotals.errors = backfillTotals.errors + r.errors
182. if (r.error != '') { console.log('adult backfill: ' + r.error) }
183. if (r.adult != null) {
184. refreshCatalogShow(id)
185. searchTitleChanged(id)
186. }
187. took = now() - t
188. if (took > 1500) { console.log('adult backfill: slow step, show ' + id + ', ' + took + ' ms') }
189. backfillNextAt = now() + r.requests * msPerRequest
190. finished = backfillPos >= backfillQueue.length
191. if (finished || backfillPos % 25 == 0) { persistSync() }
192. if (!finished && backfillTotals.titles > 0 && backfillTotals.titles % backfillLogEvery == 0 && !r.retry) { console.log('adult backfill: ' + backfillLine()) }
193. if (finished) {
194. backfillDone = true
195. console.log('adult backfill done: ' + backfillLine())
196. }
197. return null
198. }
200. // ---- THE DETAILS REPAIR (tracker.worldapi.org#26, details.hl completeStep) ----------------------------------------
201. // Titles the person pages added from a filmography (#16) are MINIMAL (no poster, seasons, ids, cast …). Opening one completes
202. // it (components/show.hl); this job completes the rest in the background: at start it collects every incomplete, non-adult
203. // title and does ONE per step on the app's clock, like the adult backfill — a step is the daily sync's work for one title
204. // (1–3 TMDB requests + poster + TVmaze), requests queued meanwhile are served between two steps; then a pause of 260 ms per
205. // TMDB request + 550 ms per TVmaze request (pauseMsAfter: ≤ 40 / 10 s, ≤ 20 / 10 s). RESUMES after a restart (the queue is
206. // what is still incomplete); TMDB 404 marks the title (`detailsCheck`), a passing failure goes to the back of the queue (at
207. // most 2 more tries per start). Waits while the daily sync or the adult backfill runs. Persisted every 10 titles + at the end.
208. // Log: start, every TRACKER_REPAIR_LOG_EVERY (100) titles, each failure, done. TRACKER_REPAIR=0 switches it off (off too
209. // without TMDB_READ_TOKEN). TRACKER_REPAIR_FAST=1 (the gate, fake TMDB only): 20 ms pause instead.
210. repairOn = env('TRACKER_REPAIR') != '0' && syncEnabled
211. repairFast = env('TRACKER_REPAIR_FAST') == '1'
212. repairLogEvery = env('TRACKER_REPAIR_LOG_EVERY') != null ? toNumber(env('TRACKER_REPAIR_LOG_EVERY')) : 100
213. repairQueue = null
214. repairPos = 0
215. repairDone = false
216. repairAt = 0
217. repairNextAt = 0
218. repairTries = {}
219. repairTotals = { titles = 0 complete = 0 failed = 0 adult = 0 newSeasons = 0 newEpisodes = 0 posters = 0 cast = 0 requests = 0 tvmazeRequests = 0 retries = 0 }
220. repairLine = () => {
221. b = repairTotals
222. return b.titles + '/' + repairQueue.length + ' titles, complete=' + b.complete + ' failed=' + b.failed + ' adult=' + b.adult + ' newSeasons=' + b.newSeasons + ' newEpisodes=' + b.newEpisodes
223. + ' posters=' + b.posters + ' cast=' + b.cast + ' requests=' + b.requests + ' tvmazeRequests=' + b.tvmazeRequests + ' retries=' + b.retries + ' seconds=' + ((now() - repairAt) / 1000)
224. }
225. repairTick = () => {
226. if (repairDone) { return null }
227. t = now()
228. if (repairQueue == null) {
229. repairQueue = detailsRepairQueue()
230. repairAt = t
231. console.log('details repair: start, ' + repairQueue.length + ' incomplete titles (' + (now() - t) + ' ms to find them)')
232. if (repairQueue.length == 0) { repairDone = true console.log('details repair: nothing to do') }
233. return null
234. }
235. if (t < repairNextAt) { return null }
236. id = repairQueue[repairPos]
237. r = completeStep(id)
238. took = now() - t
239. if (took > 1500) { console.log('details repair: slow step, show ' + id + ', ' + took + ' ms') }
240. if (r.error != '') { console.log('details repair: ' + r.error) }
241. repairNextAt = now() + (repairFast ? 20 : pauseMsAfter(r))
242. // a series takes several steps: the same title again next time
243. if (!r.done && !r.retry) { return null }
244. repairPos = repairPos + 1
245. if (r.retry && (repairTries[id] == null || repairTries[id] < 2)) {
246. repairTries[id] = repairTries[id] == null ? 1 : repairTries[id] + 1
247. q = repairQueue
248. q.push(id)
249. repairQueue = q
250. repairTotals.retries = repairTotals.retries + 1
251. } else if (!r.skipped) {
252. // done: its counts (a failed first step: the request alone)
253. sum = r.sum
254. repairTotals.titles = repairTotals.titles + 1
255. if (r.adult == true) { repairTotals.adult = repairTotals.adult + 1 } else if (sum.steps == 0) { repairTotals.failed = repairTotals.failed + 1 } else { repairTotals.complete = repairTotals.complete + 1 }
256. repairTotals.newSeasons = repairTotals.newSeasons + sum.newSeasons
257. repairTotals.newEpisodes = repairTotals.newEpisodes + sum.newEpisodes
258. repairTotals.posters = repairTotals.posters + sum.posters
259. repairTotals.cast = repairTotals.cast + sum.cast
260. repairTotals.requests = repairTotals.requests + (sum.steps == 0 ? r.requests : sum.requests)
261. repairTotals.tvmazeRequests = repairTotals.tvmazeRequests + sum.tvmazeRequests
262. }
263. finished = repairPos >= repairQueue.length
264. if (finished || repairPos % 10 == 0) { persistDetails() }
265. if (!finished && !r.skipped && !r.retry && repairTotals.titles > 0 && repairTotals.titles % repairLogEvery == 0) { console.log('details repair: ' + repairLine()) }
266. if (finished) {
267. repairDone = true
268. console.log('details repair done: ' + repairLine())
269. }
270. return null
271. }
273. // ---- THE KIND BACKFILL (tracker.worldapi.org#21, mission 068, backfill.hl kindTitle) ----------------------------------
274. // Every TV title without a stored kind gets one: a title with a show genre (Reality / Talk / News) from the stored data (no
275. // request), any other from TMDB's TV type (ONE request: GET /tv/<id>, then 260 ms pause). Starts once the adult backfill is
276. // done (or off), waits while the daily sync runs, ONE title per step on the same clock. A title that becomes a SHOW moves from
277. // /series to /shows at once (catalog.hl refreshCatalogShow). RESUMES after a restart (the queue = every TV title still without
278. // a kind); a 404 stores the genres' answer + `kindCheck`; a passing failure goes to the back of the queue, at most 2 more tries
279. // per start. Log: start, every TRACKER_KINDS_LOG_EVERY (500) titles, done. TRACKER_KINDS=0 switches it off; without
280. // TMDB_READ_TOKEN it is off too (the lists still split by genre: shows.hl titleKind).
281. kindsOn = env('TRACKER_KINDS') != '0' && syncEnabled
282. kindsLogEvery = env('TRACKER_KINDS_LOG_EVERY') != null ? toNumber(env('TRACKER_KINDS_LOG_EVERY')) : 500
283. kindQueue = null
284. kindPos = 0
285. kindDone = false
286. kindAt = 0
287. kindNextAt = 0
288. kindTries = {}
289. kindTotals = { titles = 0 series = 0 shows = 0 fromGenre = 0 moved = 0 requests = 0 errors = 0 retries = 0 }
290. kindLine = () => {
291. k = kindTotals
292. return k.titles + '/' + kindQueue.length + ' titles, series=' + k.series + ' shows=' + k.shows + ' fromGenre=' + k.fromGenre + ' moved=' + k.moved
293. + ' requests=' + k.requests + ' errors=' + k.errors + ' retries=' + k.retries + ' seconds=' + ((now() - kindAt) / 1000)
294. }
295. kindTick = () => {
296. if (kindDone) { return null }
297. t = now()
298. if (kindQueue == null) {
299. kindQueue = kindBackfillQueue()
300. kindAt = t
301. console.log('kind backfill: start, ' + kindQueue.length + ' TV titles without a kind (' + (now() - t) + ' ms to find them)')
302. if (kindQueue.length == 0) { kindDone = true console.log('kind backfill: nothing to do') }
303. return null
304. }
305. if (t < kindNextAt) { return null }
306. id = kindQueue[kindPos]
307. kindPos = kindPos + 1
308. r = kindTitle(id)
309. if (r.retry && (kindTries[id] == null || kindTries[id] < 2)) {
310. kindTries[id] = kindTries[id] == null ? 1 : kindTries[id] + 1
311. q = kindQueue
312. q.push(id)
313. kindQueue = q
314. kindTotals.retries = kindTotals.retries + 1
315. } else {
316. kindTotals.titles = kindTotals.titles + 1
317. if (r.kind == 'show') { kindTotals.shows = kindTotals.shows + 1 } else if (r.kind == 'series') { kindTotals.series = kindTotals.series + 1 }
318. if (r.fromGenre) { kindTotals.fromGenre = kindTotals.fromGenre + 1 }
319. }
320. kindTotals.requests = kindTotals.requests + r.requests
321. kindTotals.errors = kindTotals.errors + r.errors
322. if (r.error != '') { console.log('kind backfill: ' + r.error) }
323. // until now the genres decided (no show genre = series): only a title TMDB's type makes a SHOW changes its list
324. if (r.kind == 'show' && !r.fromGenre) {
325. refreshCatalogShow(id)
326. kindTotals.moved = kindTotals.moved + 1
327. }
328. took = now() - t
329. if (took > 1500) { console.log('kind backfill: slow step, show ' + id + ', ' + took + ' ms') }
330. kindNextAt = now() + r.requests * msPerRequest
331. finished = kindPos >= kindQueue.length
332. if (finished || kindPos % 25 == 0) { persistSync() }
333. if (!finished && kindTotals.titles > 0 && kindTotals.titles % kindsLogEvery == 0 && !r.retry) { console.log('kind backfill: ' + kindLine()) }
334. if (finished) {
335. kindDone = true
336. console.log('kind backfill done: ' + kindLine())
337. }
338. return null
339. }
341. // ---- THE CREDITS JOB (tracker.worldapi.org#28, credits.hl creditsStep) ------------------------------------------------------
342. // Every PUBLIC title (adult == false; adult/unknown are skipped) with a TMDB id gets its whole cast + crew once: at start (after
343. // the adult backfill and the details repair are done) the job collects every such title without `creditsAt` and asks ONE per
344. // step on the app's clock — one request (details + credits / aggregate_credits), then 260 ms (≤ 40 / 10 s); requests queued
345. // meanwhile are served between two steps. Titles the details repair, the daily sync or an import already did carry `creditsAt`
346. // and are not asked. RESUMES after a restart (the queue = what still has no credits); TMDB 404 / adult → `creditsCheck`, never
347. // asked again; a passing failure goes to the back of the queue (at most 2 more tries per start). Waits while the daily sync
348. // runs. Persisted (shows + people only) every 25 titles + at the end. Log: start, every TRACKER_CREDITS_LOG_EVERY (500) titles, failures, slow steps
349. // (> 1.5 s), done. TRACKER_CREDITS=0 switches it off (off too without TMDB_READ_TOKEN). TRACKER_CREDITS_FAST=1 (the gate, fake
350. // TMDB only): 20 ms pause instead.
351. // MEMORY LIMIT: the runtime never frees what a fetch + JSON parse took (mission 061 measured ~6 MB more RSS per title on the real
352. // data, 300 fetches of a 440 KB JSON = +2.7 GB in a 10-line script) — the whole job in one process would add ~35 GB. Before every
353. // step the job reads its own RSS (/proc/self/status); above TRACKER_CREDITS_MAX_MB (16000) it PAUSES for this process (logged) and
354. // goes on with what is left after the next restart (deploy, `docker restart`).
355. creditsOn = env('TRACKER_CREDITS') != '0' && syncEnabled
356. creditsFast = env('TRACKER_CREDITS_FAST') == '1'
357. creditsLogEvery = env('TRACKER_CREDITS_LOG_EVERY') != null ? toNumber(env('TRACKER_CREDITS_LOG_EVERY')) : 500
358. creditsQueueIds = null
359. creditsPos = 0
360. creditsDone = false
361. creditsAt = 0
362. creditsNextAt = 0
363. creditsTries = {}
364. creditsMaxMb = env('TRACKER_CREDITS_MAX_MB') != null ? toNumber(env('TRACKER_CREDITS_MAX_MB')) : 16000
365. creditsTotals = { titles = 0 done = 0 failed = 0 cast = 0 crew = 0 people = 0 requests = 0 retries = 0 slowest = 0 }
366. // this process' resident memory in MB (VmRSS), 0 when unknown
367. rssMb = () => {
368. if (!exists('/proc/self/status')) { return 0 }
369. t = readFile('/proc/self/status')
370. i = t != null ? t.indexOf('VmRSS:') : -1
371. if (i < 0) { return 0 }
372. kb = toNumber(t.slice(i + 6, t.indexOf('kB', i)).trim())
373. return kb != null ? (kb - kb % 1024) / 1024 : 0
374. }
375. creditsLine = () => {
376. b = creditsTotals
377. return b.titles + '/' + creditsQueueIds.length + ' titles, done=' + b.done + ' failed=' + b.failed + ' cast=' + b.cast + ' crew=' + b.crew + ' people=' + b.people
378. + ' requests=' + b.requests + ' retries=' + b.retries + ' slowestStepMs=' + b.slowest + ' seconds=' + ((now() - creditsAt) / 1000)
379. }
380. creditsTick = () => {
381. if (creditsDone) { return null }
382. t = now()
383. if (creditsQueueIds == null) {
384. creditsQueueIds = creditsQueue()
385. creditsAt = t
386. console.log('credits job: start, ' + creditsQueueIds.length + ' titles without credits (' + (now() - t) + ' ms to find them)')
387. if (creditsQueueIds.length == 0) { creditsDone = true console.log('credits job: nothing to do') }
388. return null
389. }
390. if (t < creditsNextAt) { return null }
391. mb = rssMb()
392. if (mb > creditsMaxMb) {
393. creditsDone = true
394. persistCredits()
395. console.log('credits job: paused, RSS ' + mb + ' MB > TRACKER_CREDITS_MAX_MB ' + creditsMaxMb + ' — ' + (creditsQueueIds.length - creditsPos) + ' titles left for the next start; ' + creditsLine())
396. return null
397. }
398. id = creditsQueueIds[creditsPos]
399. creditsPos = creditsPos + 1
400. r = creditsStep(id)
401. took = now() - t
402. if (took > creditsTotals.slowest) { creditsTotals.slowest = took }
403. if (took > 1500) { console.log('credits job: slow step, show ' + id + ', ' + took + ' ms, cast=' + r.cast + ' crew=' + r.crew + ' people=' + r.people) }
404. if (r.error != '') { console.log('credits job: ' + r.error) }
405. creditsNextAt = now() + (creditsFast ? 20 : r.requests * msPerRequest)
406. if (r.retry && (creditsTries[id] == null || creditsTries[id] < 2)) {
407. creditsTries[id] = creditsTries[id] == null ? 1 : creditsTries[id] + 1
408. q = creditsQueueIds
409. q.push(id)
410. creditsQueueIds = q
411. creditsTotals.retries = creditsTotals.retries + 1
412. } else if (!r.skipped) {
413. creditsTotals.titles = creditsTotals.titles + 1
414. if (r.error != '') { creditsTotals.failed = creditsTotals.failed + 1 } else { creditsTotals.done = creditsTotals.done + 1 }
415. creditsTotals.cast = creditsTotals.cast + r.cast
416. creditsTotals.crew = creditsTotals.crew + r.crew
417. creditsTotals.people = creditsTotals.people + r.people
418. }
419. creditsTotals.requests = creditsTotals.requests + r.requests
420. finished = creditsPos >= creditsQueueIds.length
421. if (finished || creditsPos % 25 == 0) { persistCredits() }
422. if (!finished && !r.skipped && !r.retry && creditsTotals.titles > 0 && creditsTotals.titles % creditsLogEvery == 0) { console.log('credits job: ' + creditsLine()) }
423. if (finished) {
424. creditsDone = true
425. console.log('credits job done: ' + creditsLine())
426. }
427. return null
428. }
430. // ---- THE DUPLICATE MERGE (tracker.worldapi.org#31, mission 026, merge.hl) ----------------------------------------------------
431. // The old tracker's duplicate titles (same type + TMDB id) become ONE record each — follows, watches, seasons, cast, credits and
432. // timelines moved to the keeper, the others kept as tombstones whose slug and short id lead to it — and seasons that name a title
433. // without being in its list are merged into their twin or linked (merge.hl has the rules). On its own clock (every 0.1 s) ONE
434. // item per tick (a duplicate group, or one title's stray seasons; no TMDB), so pages are served between two items. The TMDB jobs
435. // below (daily sync, backfills, repair, credits, collections) wait until it is done: none of them works on a record that is about
436. // to become a tombstone. RESUMES after a restart (the queue = what is still duplicated / stray). Persisted every 10 items and at
437. // the end. Log: start, one line per tombstone / repaired title, done; with nothing to do one line. TRACKER_MERGE=0 switches it off.
438. mergeOn = env('TRACKER_MERGE') != '0'
439. mergeRun = { started = false done = !mergeOn at = 0 items = 0 slowest = 0 }
440. mergeTick = () => {
441. if (mergeRun.done) { return null }
442. t = now()
443. if (!mergeRun.started) {
444. mergeRun.started = true
445. mergeRun.at = t
446. n = mergePrepare()
447. st = mergeStats()
448. console.log('merge: start, ' + st.groups + ' duplicate groups (' + st.records + ' records), ' + st.strayTitles + ' titles with stray seasons (' + st.ms + ' ms to find them)')
449. if (n == 0) {
450. mergeRun.done = true
451. console.log('merge: nothing to do')
452. }
453. return null
454. }
455. r = mergeStep()
456. mergeRun.items = mergeRun.items + 1
457. took = now() - t
458. if (took > mergeRun.slowest) { mergeRun.slowest = took }
459. if (took > 1500) { console.log('merge: slow step, ' + took + ' ms') }
460. if (r.done || mergeRun.items % 10 == 0) { persistMerge() }
461. if (r.done) {
462. mergeRun.done = true
463. console.log('merge done: ' + mergeLine() + ' items=' + mergeRun.items + ' slowestStepMs=' + mergeRun.slowest + ' seconds=' + ((now() - mergeRun.at) / 1000))
464. }
465. return null
466. }
467. if (!mergeOn) { console.log('merge: off (TRACKER_MERGE=0)') }
468. else {
469. mergeClock = every(0.1)
470. on mergeClock.tick(x) {
471. mergeTick()
472. return null
473. }
474. }
476. // ONE clock for all five (a tick every 0.1 s): the daily sync's step once a second as before; the backfill's steps in
477. // between, only while no daily run is going on; the details repair only when neither has work; then the kind backfill (#21);
478. // the credits job (#28) last
479. clockOn = syncOn || backfillOn || repairOn || kindsOn || creditsOn
480. clockTicks = 0
481. if (syncOn) { console.log('tmdb sync: daily at ' + syncHour + ':00 UTC') } else { console.log('tmdb sync: off (' + (syncEnabled ? 'TRACKER_SYNC=0' : 'no TMDB_READ_TOKEN') + ')') }
482. if (!backfillOn) { console.log('adult backfill: off (' + (syncEnabled ? 'TRACKER_BACKFILL=0' : 'no TMDB_READ_TOKEN') + ')') }
483. if (!repairOn) { console.log('details repair: off (' + (syncEnabled ? 'TRACKER_REPAIR=0' : 'no TMDB_READ_TOKEN') + ')') }
484. if (!creditsOn) { console.log('credits job: off (' + (syncEnabled ? 'TRACKER_CREDITS=0' : 'no TMDB_READ_TOKEN') + ')') }
485. if (!kindsOn) { console.log('kind backfill: off (' + (syncEnabled ? 'TRACKER_KINDS=0' : 'no TMDB_READ_TOKEN') + ')') }
486. if (clockOn) {
487. syncClock = every(0.1)
488. on syncClock.tick(x) {
489. clockTicks = clockTicks + 1
490. // tracker#31: not before the duplicate merge is done
491. if (!mergeRun.done) { return null }
492. busy = syncQueue.length > 0 || syncPlanning
493. if (syncOn && clockTicks % 10 == 0) { syncTick() }
494. free = !busy && syncQueue.length == 0 && !syncPlanning
495. if (backfillOn && free && !backfillDone) { backfillTick() }
496. else if (repairOn && free && (!backfillOn || backfillDone) && !repairDone) { repairTick() }
497. else if (kindsOn && free && (!backfillOn || backfillDone) && (!repairOn || repairDone) && !kindDone) { kindTick() }
498. else if (creditsOn && free && (!backfillOn || backfillDone) && (!repairOn || repairDone) && (!kindsOn || kindDone)) { creditsTick() }
499. return null
500. }
501. }
503. // ---- THE SHORT-ID BACKFILL (mission 060, tracker.worldapi.org#27, shortids.hl shortIdStep) ----------------------------
504. // Every show and person without a short id gets one in the background: on its own clock (every 0.1 s) ONE batch per tick,
505. // as many records as fit into TRACKER_SHORTID_BUDGET_MS (default 40 ms) — no TMDB, only the tables — so a page waits at
506. // most one batch. The queue is built at start (`ensureShortIds`) from what is still missing = it resumes after a restart.
507. // Persisted every 20 batches and at the end. Log: start, every TRACKER_SHORTID_LOG_EVERY (5000) records, done; with nothing
508. // to do one line. TRACKER_SHORTIDS=0 switches it off (the gate's resume check).
509. shortIdOn = env('TRACKER_SHORTIDS') != '0'
510. shortIdBudget = env('TRACKER_SHORTID_BUDGET_MS') != null ? toNumber(env('TRACKER_SHORTID_BUDGET_MS')) : 40
511. shortIdLogEvery = env('TRACKER_SHORTID_LOG_EVERY') != null ? toNumber(env('TRACKER_SHORTID_LOG_EVERY')) : 5000
512. shortIdRun = { at = 0 batches = 0 assigned = 0 nextLog = 0 slowest = 0 slowestPersist = 0 done = false }
513. shortIdTick = () => {
514. if (shortIdRun.done) { return null }
515. t = now()
516. r = shortIdStep(shortIdBudget)
517. took = now() - t
518. shortIdRun.batches = shortIdRun.batches + 1
519. shortIdRun.assigned = shortIdRun.assigned + r.assigned
520. if (took > shortIdRun.slowest) { shortIdRun.slowest = took }
521. if (r.done || shortIdRun.batches % 20 == 0) {
522. tp = now()
523. persistShortIds()
524. if (now() - tp > shortIdRun.slowestPersist) { shortIdRun.slowestPersist = now() - tp }
525. }
526. if (!r.done && shortIdRun.assigned >= shortIdRun.nextLog) {
527. console.log('short ids: ' + shortIdRun.assigned + ' assigned, ' + r.left + ' left, ' + ((now() - shortIdRun.at) / 1000) + ' s')
528. shortIdRun.nextLog = shortIdRun.nextLog + shortIdLogEvery
529. }
530. if (r.done) {
531. shortIdRun.done = true
532. s = shortIdStats()
533. console.log('short ids done: assigned=' + shortIdRun.assigned + ' oldKept=' + s.oldKept + ' batches=' + shortIdRun.batches + ' slowestBatchMs=' + shortIdRun.slowest + ' slowestPersistMs=' + shortIdRun.slowestPersist + ' seconds=' + ((now() - shortIdRun.at) / 1000))
534. }
535. return null
536. }
537. if (!shortIdOn) { console.log('short ids: backfill off (TRACKER_SHORTIDS=0), ' + shortIdsLeft() + ' records without one') }
538. else if (shortIdsLeft() == 0) { console.log('short ids: nothing to do') }
539. else {
540. shortIdRun.at = now()
541. shortIdRun.nextLog = shortIdLogEvery
542. console.log('short ids: backfill start, ' + shortIdsLeft() + ' records, ' + shortIdBudget + ' ms per batch')
543. shortIdClock = every(0.1)
544. on shortIdClock.tick(x) {
545. shortIdTick()
546. return null
547. }
548. }
550. // ---- THE COLLECTION SEED (mission 066, tracker.worldapi.org#19, collections.hl seedStep) ------------------------------------
551. // TMDB's movie collections become timelines: ONE movie per step (its details; once per collection the collection's parts), on
552. // its own 0.1 s clock like the adult backfill — a pause of 260 ms per TMDB request made (≤ 40 / 10 s). It WAITS while the daily
553. // sync or the adult backfill runs (they share TMDB's limit and the backfill brings the adult flags it skips by); since the merge
554. // (mission 070) also until the details repair, the kind backfill and the credits job are done (or paused) — the LAST job. The queue =
555. // every non-adult movie without a check row, so it RESUMES after a restart and does nothing once all are checked. Persisted
556. // every TRACKER_COLLECTIONS_PERSIST_EVERY (25) steps and at the end. Log: start, every TRACKER_COLLECTIONS_LOG_EVERY (500) movies, done.
557. // TRACKER_COLLECTIONS=0 switches it off; without TMDB_READ_TOKEN it is off too.
558. collectionsOn = env('TRACKER_COLLECTIONS') != '0' && syncEnabled
559. collectionsLogEvery = env('TRACKER_COLLECTIONS_LOG_EVERY') != null ? toNumber(env('TRACKER_COLLECTIONS_LOG_EVERY')) : 500
560. collectionsPersistEvery = env('TRACKER_COLLECTIONS_PERSIST_EVERY') != null ? toNumber(env('TRACKER_COLLECTIONS_PERSIST_EVERY')) : 25
561. collectionRun = { at = 0 steps = 0 nextAt = 0 done = false started = false slowest = 0 }
562. collectionTick = () => {
563. if (collectionRun.done) { return null }
564. // mission 070 (merge): also behind the details repair, the kind backfill and the credits job — one TMDB pacing at a time
565. if (!mergeRun.done || syncQueue.length > 0 || syncPlanning || (backfillOn && !backfillDone) || (repairOn && !repairDone) || (kindsOn && !kindDone) || (creditsOn && !creditsDone)) { return null }
566. t = now()
567. if (!collectionRun.started) {
568. collectionRun.started = true
569. collectionRun.at = t
570. n = seedPrepare()
571. console.log('collections: start, ' + n + ' movies to check (' + (now() - t) + ' ms to find them)')
572. if (n == 0) { collectionRun.done = true console.log('collections: nothing to do') }
573. return null
574. }
575. if (t < collectionRun.nextAt) { return null }
576. r = seedStep()
577. if (r.done) {
578. collectionRun.done = true
579. seedPersist()
580. console.log('collections done: ' + seedLine() + ' seconds=' + ((now() - collectionRun.at) / 1000) + ' slowestStepMs=' + collectionRun.slowest)
581. return null
582. }
583. collectionRun.steps = collectionRun.steps + 1
584. took = now() - t
585. if (took > collectionRun.slowest) { collectionRun.slowest = took }
586. if (r.error != '') { console.log('collections: ' + r.error) }
587. if (took > 1500) { console.log('collections: slow step, ' + took + ' ms') }
588. collectionRun.nextAt = now() + r.requests * msPerRequest
589. if (collectionRun.steps % collectionsPersistEvery == 0) { seedPersist() }
590. if (collectionRun.steps % collectionsLogEvery == 0) { console.log('collections: ' + seedLine() + ' seconds=' + ((now() - collectionRun.at) / 1000)) }
591. return null
592. }
593. if (!collectionsOn) { console.log('collections: off (' + (syncEnabled ? 'TRACKER_COLLECTIONS=0' : 'no TMDB_READ_TOKEN') + ')') }
594. else {
595. collectionClock = every(0.1)
596. on collectionClock.tick(x) {
597. collectionTick()
598. return null
599. }
600. }