Intermittent "Recording Not Found" error in Moodle [Scalelite 1.6.5 + BBB 3.0.15]

[morexzamani]

Describe the bug
Hello Scalelite team,
I’ve encountered an intermittent issue with recordings when using Scalelite with Moodle integration.

Recordings are correctly imported from BigBlueButton servers into Scalelite (they appear in the Scalelite DB), but when users try to open them inside Moodle, the behavior is inconsistent:

• Sometimes the recording opens normally.
• Sometimes Scalelite shows the following error:

Recording Not Found
The recording URL is invalid. This recording may have been either protected or deleted.

All services (scalelite-api, scalelite-recording-importer, etc.) are running normally without errors.

To Reproduce

• Integrate Moodle with Scalelite (latest versions).
• Create and record a session in BigBlueButton.
• Verify that the recording is imported into Scalelite successfully.
• Try to open the recording from Moodle multiple times.
• Observe that sometimes it opens fine, sometimes it fails with the error above.

Deployment:

1. Deployment: default systemd deployment
2. Versions: BigBlueButton servers: 3.0.15, Scalelite: 1.6.5
3. Tools used: Moodle integration with BigBlueButton plugin

Steps to reproduce the behavior:

• Start a meeting in Moodle (BBB backend via Scalelite).
• End the meeting and wait until the recording is processed/imported.
• Open the course in Moodle and try to play the recording.
• Sometimes it works, sometimes the error “Recording Not Found” appears.

Expected behavior
Recordings should always open normally once they are imported and listed in Scalelite.

Additional context

• All Scalelite services show as running normally.
• No manual intervention is needed for recordings to be imported; the importer works fine.
• The issue only appears intermittently.

For example, for the recording with record_id: 9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990
(as shown in the logs I attached), Scalelite sometimes returns it successfully, but other times it shows Completed 404 Not Found even though the same recording is listed in the database and was already imported.

# docker logs -f --tail 10 scalelite-api
I, [2025-09-30T17:30:47.281639 #17]  INFO -- : [590434bc-3761-4560-8ed7-05e4e6a0f8b6] Started GET "/bigbluebutton/api/getMeetingInfo?meetingID=692d95b05f88fd93cdccee7254510c6a484493ba-10-7%5B0%5D&checksum=8b187ed78728dca20eba3cb2fbac026ea2114094840f51b3940c9ed6fc3aea0f" for 88.135.68.1 at 2025-09-30 17:30:47 +0000
I, [2025-09-30T17:30:47.282331 #17]  INFO -- : [590434bc-3761-4560-8ed7-05e4e6a0f8b6] Processing by BigBlueButtonApiController#get_meeting_info as XML
I, [2025-09-30T17:30:47.282420 #17]  INFO -- : [590434bc-3761-4560-8ed7-05e4e6a0f8b6]   Parameters: {"meetingID"=>"692d95b05f88fd93cdccee7254510c6a484493ba-10-7[0]", "checksum"=>"8b187ed78728dca20eba3cb2fbac026ea2114094840f51b3940c9ed6fc3aea0f"}
I, [2025-09-30T17:30:47.282962 #17]  INFO -- : [590434bc-3761-4560-8ed7-05e4e6a0f8b6] The requested meeting 692d95b05f88fd93cdccee7254510c6a484493ba-10-7[0] does not exist
I, [2025-09-30T17:30:47.283411 #17]  INFO -- : [590434bc-3761-4560-8ed7-05e4e6a0f8b6] Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:31:11.367988 #17]  INFO -- : [5fe26c9b-fde8-4f15-a29f-0e5096ea12e0] Started GET "/bigbluebutton/api/getMeetingInfo?meetingID=692d95b05f88fd93cdccee7254510c6a484493ba-8-5%5B0%5D&checksum=4fd8e8e44f8e72354e699430fe4ffc3bd6164475f688728230cf89ddf7a91643" for 88.135.68.1 at 2025-09-30 17:31:11 +0000
I, [2025-09-30T17:31:11.368659 #17]  INFO -- : [5fe26c9b-fde8-4f15-a29f-0e5096ea12e0] Processing by BigBlueButtonApiController#get_meeting_info as XML
I, [2025-09-30T17:31:11.368710 #17]  INFO -- : [5fe26c9b-fde8-4f15-a29f-0e5096ea12e0]   Parameters: {"meetingID"=>"692d95b05f88fd93cdccee7254510c6a484493ba-8-5[0]", "checksum"=>"4fd8e8e44f8e72354e699430fe4ffc3bd6164475f688728230cf89ddf7a91643"}
I, [2025-09-30T17:31:11.369269 #17]  INFO -- : [5fe26c9b-fde8-4f15-a29f-0e5096ea12e0] The requested meeting 692d95b05f88fd93cdccee7254510c6a484493ba-8-5[0] does not exist
I, [2025-09-30T17:31:11.369714 #17]  INFO -- : [5fe26c9b-fde8-4f15-a29f-0e5096ea12e0] Completed 200 OK in 1ms (Views: 0.1ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)

I, [2025-09-30T17:31:47.275856 #17]  INFO -- : [2697fb6a-f35b-4a75-ae0b-99f37e1aa701] Started GET "/bigbluebutton/api/getMeetingInfo?meetingID=692d95b05f88fd93cdccee7254510c6a484493ba-10-7%5B0%5D&checksum=8b187ed78728dca20eba3cb2fbac026ea2114094840f51b3940c9ed6fc3aea0f" for 88.135.68.1 at 2025-09-30 17:31:47 +0000
I, [2025-09-30T17:31:47.277153 #17]  INFO -- : [2697fb6a-f35b-4a75-ae0b-99f37e1aa701] Processing by BigBlueButtonApiController#get_meeting_info as XML
I, [2025-09-30T17:31:47.277256 #17]  INFO -- : [2697fb6a-f35b-4a75-ae0b-99f37e1aa701]   Parameters: {"meetingID"=>"692d95b05f88fd93cdccee7254510c6a484493ba-10-7[0]", "checksum"=>"8b187ed78728dca20eba3cb2fbac026ea2114094840f51b3940c9ed6fc3aea0f"}
I, [2025-09-30T17:31:47.278323 #17]  INFO -- : [2697fb6a-f35b-4a75-ae0b-99f37e1aa701] The requested meeting 692d95b05f88fd93cdccee7254510c6a484493ba-10-7[0] does not exist
I, [2025-09-30T17:31:47.279221 #17]  INFO -- : [2697fb6a-f35b-4a75-ae0b-99f37e1aa701] Completed 200 OK in 2ms (Views: 0.3ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:06.738604 #17]  INFO -- : [018e4896-013a-4185-953b-df30b2b063ae] Started GET "/bigbluebutton/api/getRecordings?recordID=9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703&state=any&checksum=32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922" for 88.135.68.1 at 2025-09-30 17:32:06 +0000
I, [2025-09-30T17:32:06.739325 #17]  INFO -- : [018e4896-013a-4185-953b-df30b2b063ae] Processing by BigBlueButtonApiController#get_recordings as XML
I, [2025-09-30T17:32:06.739386 #17]  INFO -- : [018e4896-013a-4185-953b-df30b2b063ae]   Parameters: {"recordID"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703", "state"=>"any", "checksum"=>"32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922"}
D, [2025-09-30T17:32:06.740489 #17] DEBUG -- : [018e4896-013a-4185-953b-df30b2b063ae]   Rendering bigbluebutton_api/get_recordings.xml.builder
D, [2025-09-30T17:32:06.742792 #17] DEBUG -- : [018e4896-013a-4185-953b-df30b2b063ae]   Recording Load (0.5ms)  SELECT DISTINCT "recordings".* FROM "recordings" LEFT OUTER JOIN "metadata" ON "metadata"."recording_id" = "recordings"."id" WHERE (record_id LIKE $1 ESCAPE '|' OR record_id LIKE $2 ESCAPE '|' OR record_id LIKE $3 ESCAPE '|' OR record_id LIKE $4 ESCAPE '|') ORDER BY "recordings"."starttime" DESC  [[nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703%"]]
D, [2025-09-30T17:32:06.744516 #17] DEBUG -- : [018e4896-013a-4185-953b-df30b2b063ae]   PlaybackFormat Load (0.4ms)  SELECT "playback_formats".* FROM "playback_formats" WHERE "playback_formats"."recording_id" IN ($1, $2, $3) ORDER BY "playback_formats"."format" ASC  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:32:06.745493 #17] DEBUG -- : [018e4896-013a-4185-953b-df30b2b063ae]   Metadatum Load (0.4ms)  SELECT "metadata".* FROM "metadata" WHERE "metadata"."recording_id" IN ($1, $2, $3)  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:32:06.746994 #17] DEBUG -- : [018e4896-013a-4185-953b-df30b2b063ae]   Thumbnail Load (0.3ms)  SELECT "thumbnails".* FROM "thumbnails" WHERE "thumbnails"."playback_format_id" IN ($1, $2, $3) ORDER BY "thumbnails"."sequence" ASC  [["playback_format_id", 29], ["playback_format_id", 30], ["playback_format_id", 31]]
I, [2025-09-30T17:32:06.752125 #17]  INFO -- : [018e4896-013a-4185-953b-df30b2b063ae]   Rendered bigbluebutton_api/get_recordings.xml.builder (Duration: 11.6ms | GC: 0.8ms)
I, [2025-09-30T17:32:06.752340 #17]  INFO -- : [018e4896-013a-4185-953b-df30b2b063ae] Completed 200 OK in 13ms (Views: 10.5ms | ActiveRecord: 1.5ms (4 queries, 0 cached) | GC: 0.8ms)
I, [2025-09-30T17:32:06.787186 #17]  INFO -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536] Started GET "/bigbluebutton/api/getRecordings?recordID=9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703&state=any&checksum=32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922" for 88.135.68.1 at 2025-09-30 17:32:06 +0000
I, [2025-09-30T17:32:06.787933 #17]  INFO -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536] Processing by BigBlueButtonApiController#get_recordings as XML
I, [2025-09-30T17:32:06.788021 #17]  INFO -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   Parameters: {"recordID"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703", "state"=>"any", "checksum"=>"32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922"}
D, [2025-09-30T17:32:06.788945 #17] DEBUG -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   Rendering bigbluebutton_api/get_recordings.xml.builder
D, [2025-09-30T17:32:06.791961 #17] DEBUG -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   Recording Load (0.5ms)  SELECT DISTINCT "recordings".* FROM "recordings" LEFT OUTER JOIN "metadata" ON "metadata"."recording_id" = "recordings"."id" WHERE (record_id LIKE $1 ESCAPE '|' OR record_id LIKE $2 ESCAPE '|' OR record_id LIKE $3 ESCAPE '|' OR record_id LIKE $4 ESCAPE '|') ORDER BY "recordings"."starttime" DESC  [[nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703%"]]
D, [2025-09-30T17:32:06.793869 #17] DEBUG -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   PlaybackFormat Load (0.4ms)  SELECT "playback_formats".* FROM "playback_formats" WHERE "playback_formats"."recording_id" IN ($1, $2, $3) ORDER BY "playback_formats"."format" ASC  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:32:06.794954 #17] DEBUG -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   Metadatum Load (0.4ms)  SELECT "metadata".* FROM "metadata" WHERE "metadata"."recording_id" IN ($1, $2, $3)  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:32:06.796750 #17] DEBUG -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   Thumbnail Load (0.4ms)  SELECT "thumbnails".* FROM "thumbnails" WHERE "thumbnails"."playback_format_id" IN ($1, $2, $3) ORDER BY "thumbnails"."sequence" ASC  [["playback_format_id", 29], ["playback_format_id", 30], ["playback_format_id", 31]]
I, [2025-09-30T17:32:06.803001 #17]  INFO -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536]   Rendered bigbluebutton_api/get_recordings.xml.builder (Duration: 14.0ms | GC: 1.1ms)
I, [2025-09-30T17:32:06.803237 #17]  INFO -- : [0ad197e8-a1ae-4832-803a-28d6c7e6f536] Completed 200 OK in 15ms (Views: 12.7ms | ActiveRecord: 1.7ms (4 queries, 0 cached) | GC: 1.1ms)
I, [2025-09-30T17:32:06.933242 #17]  INFO -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924] Started GET "/recording/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/presentation?token=3a8e063a8a499cfd5ddbb6ab59a79940472a8dd4bb31acc75c7d2eedd37423a6" for 95.215.163.71 at 2025-09-30 17:32:06 +0000
I, [2025-09-30T17:32:06.934267 #17]  INFO -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924] Processing by PlaybackController#play as HTML
I, [2025-09-30T17:32:06.934350 #17]  INFO -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924]   Parameters: {"token"=>"3a8e063a8a499cfd5ddbb6ab59a79940472a8dd4bb31acc75c7d2eedd37423a6", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "playback_format"=>"presentation"}
D, [2025-09-30T17:32:06.936224 #17] DEBUG -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924]   PlaybackFormat Load (0.4ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:06.937795 #17] DEBUG -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924]   Recording Load (0.3ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:06.939654 #17]  INFO -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924] Redirected to https://MYDOMAIN.com/playback/presentation/2.3/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990
I, [2025-09-30T17:32:06.939883 #17]  INFO -- : [9e50d204-c4ba-4a92-9b62-e7dcb3685924] Completed 307 Temporary Redirect in 5ms (ActiveRecord: 0.6ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.367997 #17]  INFO -- : [a7685baf-8618-4010-9681-379140ca7fb2] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/presentation_text.json" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.368762 #17]  INFO -- : [a7685baf-8618-4010-9681-379140ca7fb2] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.368814 #17]  INFO -- : [a7685baf-8618-4010-9681-379140ca7fb2]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"presentation_text.json"}
I, [2025-09-30T17:32:07.371372 #17]  INFO -- : [083fbb66-6c36-444d-9637-2e497c29a697] Started HEAD "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/video/webcams.mp4" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.372550 #17]  INFO -- : [083fbb66-6c36-444d-9637-2e497c29a697] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.373107 #17]  INFO -- : [083fbb66-6c36-444d-9637-2e497c29a697]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"video/webcams.mp4"}
I, [2025-09-30T17:32:07.373000 #17]  INFO -- : [54e63016-20b3-43fb-96ce-0d6e2491009f] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/captions.json" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.375804 #17]  INFO -- : [54e63016-20b3-43fb-96ce-0d6e2491009f] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.376036 #17]  INFO -- : [54e63016-20b3-43fb-96ce-0d6e2491009f]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"captions.json"}
D, [2025-09-30T17:32:07.373349 #17] DEBUG -- : [a7685baf-8618-4010-9681-379140ca7fb2]   PlaybackFormat Load (2.9ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.379382 #17] DEBUG -- : [a7685baf-8618-4010-9681-379140ca7fb2]   Recording Load (0.6ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.380236 #17]  INFO -- : [a7685baf-8618-4010-9681-379140ca7fb2] Completed 200 OK in 11ms (ActiveRecord: 3.5ms (2 queries, 0 cached) | GC: 0.8ms)
I, [2025-09-30T17:32:07.382642 #17]  INFO -- : [65db44b6-23ae-40b2-a567-d61084b495e7] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/slides_new.xml" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.385396 #17]  INFO -- : [65db44b6-23ae-40b2-a567-d61084b495e7] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.385473 #17]  INFO -- : [65db44b6-23ae-40b2-a567-d61084b495e7]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"slides_new.xml"}
D, [2025-09-30T17:32:07.386883 #17] DEBUG -- : [65db44b6-23ae-40b2-a567-d61084b495e7]   PlaybackFormat Load (0.3ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.387818 #17] DEBUG -- : [65db44b6-23ae-40b2-a567-d61084b495e7]   Recording Load (0.2ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.388454 #17]  INFO -- : [65db44b6-23ae-40b2-a567-d61084b495e7] Completed 200 OK in 3ms (ActiveRecord: 0.4ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.390532 #17]  INFO -- : [7d8cca9d-5c4f-4f58-abba-dd14362f509b] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/cursor.xml" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.391055 #17]  INFO -- : [7d8cca9d-5c4f-4f58-abba-dd14362f509b] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.391099 #17]  INFO -- : [7d8cca9d-5c4f-4f58-abba-dd14362f509b]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"cursor.xml"}
D, [2025-09-30T17:32:07.392210 #17] DEBUG -- : [7d8cca9d-5c4f-4f58-abba-dd14362f509b]   PlaybackFormat Load (0.2ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.393527 #17] DEBUG -- : [7d8cca9d-5c4f-4f58-abba-dd14362f509b]   Recording Load (0.2ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.394164 #17]  INFO -- : [7d8cca9d-5c4f-4f58-abba-dd14362f509b] Completed 200 OK in 3ms (ActiveRecord: 0.4ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.395265 #17]  INFO -- : [74a92d51-5946-404f-bd7c-4dca7fb65b32] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/metadata.xml" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.395799 #17]  INFO -- : [74a92d51-5946-404f-bd7c-4dca7fb65b32] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.395844 #17]  INFO -- : [74a92d51-5946-404f-bd7c-4dca7fb65b32]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"metadata.xml"}
D, [2025-09-30T17:32:07.396963 #17] DEBUG -- : [74a92d51-5946-404f-bd7c-4dca7fb65b32]   PlaybackFormat Load (0.2ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.397879 #17] DEBUG -- : [74a92d51-5946-404f-bd7c-4dca7fb65b32]   Recording Load (0.2ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.398700 #17]  INFO -- : [74a92d51-5946-404f-bd7c-4dca7fb65b32] Completed 200 OK in 3ms (ActiveRecord: 0.4ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.399970 #17]  INFO -- : [4015f43f-0091-4eae-a543-3f9c8ac2513e] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/notes.html" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.400687 #17]  INFO -- : [4015f43f-0091-4eae-a543-3f9c8ac2513e] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.400774 #17]  INFO -- : [4015f43f-0091-4eae-a543-3f9c8ac2513e]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"notes.html"}
D, [2025-09-30T17:32:07.401860 #17] DEBUG -- : [4015f43f-0091-4eae-a543-3f9c8ac2513e]   PlaybackFormat Load (0.3ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.403073 #17] DEBUG -- : [4015f43f-0091-4eae-a543-3f9c8ac2513e]   Recording Load (0.4ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.403927 #17]  INFO -- : [4015f43f-0091-4eae-a543-3f9c8ac2513e] Completed 200 OK in 3ms (ActiveRecord: 0.6ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.405545 #17]  INFO -- : [cecda6e3-2140-4886-b350-de2821997dee] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/panzooms.xml" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.406231 #17]  INFO -- : [cecda6e3-2140-4886-b350-de2821997dee] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.406364 #17]  INFO -- : [cecda6e3-2140-4886-b350-de2821997dee]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"panzooms.xml"}
D, [2025-09-30T17:32:07.407971 #17] DEBUG -- : [cecda6e3-2140-4886-b350-de2821997dee]   PlaybackFormat Load (0.5ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.409521 #17] DEBUG -- : [cecda6e3-2140-4886-b350-de2821997dee]   Recording Load (0.5ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.410342 #17]  INFO -- : [cecda6e3-2140-4886-b350-de2821997dee] Completed 200 OK in 4ms (ActiveRecord: 1.0ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.412304 #17]  INFO -- : [efdbfb5b-faf4-43ef-9c27-452bd31d1f81] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/polls.json" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.413168 #17]  INFO -- : [efdbfb5b-faf4-43ef-9c27-452bd31d1f81] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.413289 #17]  INFO -- : [efdbfb5b-faf4-43ef-9c27-452bd31d1f81]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"polls.json"}
D, [2025-09-30T17:32:07.415315 #17] DEBUG -- : [efdbfb5b-faf4-43ef-9c27-452bd31d1f81]   PlaybackFormat Load (0.6ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.416959 #17] DEBUG -- : [efdbfb5b-faf4-43ef-9c27-452bd31d1f81]   Recording Load (0.6ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.417744 #17]  INFO -- : [efdbfb5b-faf4-43ef-9c27-452bd31d1f81] Completed 200 OK in 4ms (ActiveRecord: 1.2ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:32:07.419529 #17]  INFO -- : [bf924f97-e7fc-4579-905c-5bfba05fcae7] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/deskshare.xml" for 95.215.163.71 at 2025-09-30 17:32:07 +0000
I, [2025-09-30T17:32:07.420415 #17]  INFO -- : [bf924f97-e7fc-4579-905c-5bfba05fcae7] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:32:07.420609 #17]  INFO -- : [bf924f97-e7fc-4579-905c-5bfba05fcae7]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"deskshare.xml"}
D, [2025-09-30T17:32:07.422825 #17] DEBUG -- : [bf924f97-e7fc-4579-905c-5bfba05fcae7]   PlaybackFormat Load (1.1ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:32:07.424957 #17] DEBUG -- : [bf924f97-e7fc-4579-905c-5bfba05fcae7]   Recording Load (0.8ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:32:07.425850 #17]  INFO -- : [bf924f97-e7fc-4579-905c-5bfba05fcae7] Completed 200 OK in 5ms (ActiveRecord: 1.9ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:35:56.633845 #17]  INFO -- : [b66350a5-5a26-4f59-b8c8-bb92f9587771] Started GET "/presentation/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/deskshare/deskshare.mp4" for 95.215.163.71 at 2025-09-30 17:35:56 +0000
I, [2025-09-30T17:35:56.634580 #17]  INFO -- : [b66350a5-5a26-4f59-b8c8-bb92f9587771] Processing by PlaybackController#resource as */*
I, [2025-09-30T17:35:56.634633 #17]  INFO -- : [b66350a5-5a26-4f59-b8c8-bb92f9587771]   Parameters: {"playback_format"=>"presentation", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "resource"=>"deskshare/deskshare.mp4"}
D, [2025-09-30T17:35:56.636318 #17] DEBUG -- : [b66350a5-5a26-4f59-b8c8-bb92f9587771]   PlaybackFormat Load (0.5ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:35:56.637483 #17] DEBUG -- : [b66350a5-5a26-4f59-b8c8-bb92f9587771]   Recording Load (0.2ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
I, [2025-09-30T17:35:56.638168 #17]  INFO -- : [b66350a5-5a26-4f59-b8c8-bb92f9587771] Completed 200 OK in 3ms (ActiveRecord: 0.8ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:35:59.757581 #17]  INFO -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df] Started GET "/bigbluebutton/api/getRecordings?recordID=9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703&state=any&checksum=32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922" for 88.135.68.1 at 2025-09-30 17:35:59 +0000
I, [2025-09-30T17:35:59.758344 #17]  INFO -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df] Processing by BigBlueButtonApiController#get_recordings as XML
I, [2025-09-30T17:35:59.758398 #17]  INFO -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   Parameters: {"recordID"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703", "state"=>"any", "checksum"=>"32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922"}
D, [2025-09-30T17:35:59.759254 #17] DEBUG -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   Rendering bigbluebutton_api/get_recordings.xml.builder
D, [2025-09-30T17:35:59.761435 #17] DEBUG -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   Recording Load (0.7ms)  SELECT DISTINCT "recordings".* FROM "recordings" LEFT OUTER JOIN "metadata" ON "metadata"."recording_id" = "recordings"."id" WHERE (record_id LIKE $1 ESCAPE '|' OR record_id LIKE $2 ESCAPE '|' OR record_id LIKE $3 ESCAPE '|' OR record_id LIKE $4 ESCAPE '|') ORDER BY "recordings"."starttime" DESC  [[nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703%"]]
D, [2025-09-30T17:35:59.763198 #17] DEBUG -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   PlaybackFormat Load (0.4ms)  SELECT "playback_formats".* FROM "playback_formats" WHERE "playback_formats"."recording_id" IN ($1, $2, $3) ORDER BY "playback_formats"."format" ASC  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:35:59.764241 #17] DEBUG -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   Metadatum Load (0.4ms)  SELECT "metadata".* FROM "metadata" WHERE "metadata"."recording_id" IN ($1, $2, $3)  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:35:59.765911 #17] DEBUG -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   Thumbnail Load (0.4ms)  SELECT "thumbnails".* FROM "thumbnails" WHERE "thumbnails"."playback_format_id" IN ($1, $2, $3) ORDER BY "thumbnails"."sequence" ASC  [["playback_format_id", 29], ["playback_format_id", 30], ["playback_format_id", 31]]
I, [2025-09-30T17:35:59.770333 #17]  INFO -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df]   Rendered bigbluebutton_api/get_recordings.xml.builder (Duration: 11.0ms | GC: 0.0ms)
I, [2025-09-30T17:35:59.770560 #17]  INFO -- : [34f79c32-467d-4226-bcf7-bcf8ba6c77df] Completed 200 OK in 12ms (Views: 9.5ms | ActiveRecord: 1.9ms (4 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:35:59.818850 #17]  INFO -- : [2de02319-0c50-4225-92d2-50c33b664958] Started GET "/recording/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/presentation?token=3a8e063a8a499cfd5ddbb6ab59a79940472a8dd4bb31acc75c7d2eedd37423a6" for 95.215.163.71 at 2025-09-30 17:35:59 +0000
I, [2025-09-30T17:35:59.819589 #17]  INFO -- : [2de02319-0c50-4225-92d2-50c33b664958] Processing by PlaybackController#play as HTML
I, [2025-09-30T17:35:59.819645 #17]  INFO -- : [2de02319-0c50-4225-92d2-50c33b664958]   Parameters: {"token"=>"3a8e063a8a499cfd5ddbb6ab59a79940472a8dd4bb31acc75c7d2eedd37423a6", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "playback_format"=>"presentation"}
D, [2025-09-30T17:35:59.821360 #17] DEBUG -- : [2de02319-0c50-4225-92d2-50c33b664958]   PlaybackFormat Load (0.5ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:35:59.822453 #17] DEBUG -- : [2de02319-0c50-4225-92d2-50c33b664958]   Recording Load (0.2ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
D, [2025-09-30T17:35:59.823345 #17] DEBUG -- : [2de02319-0c50-4225-92d2-50c33b664958]   Rendering errors/recording_not_found.html.erb
I, [2025-09-30T17:35:59.823614 #17]  INFO -- : [2de02319-0c50-4225-92d2-50c33b664958]   Rendered errors/recording_not_found.html.erb (Duration: 0.2ms | GC: 0.0ms)
I, [2025-09-30T17:35:59.823859 #17]  INFO -- : [2de02319-0c50-4225-92d2-50c33b664958] Completed 404 Not Found in 4ms (Views: 0.7ms | ActiveRecord: 0.7ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:36:00.221592 #17]  INFO -- : [b1406399-cb0c-41e9-94aa-89c0dfea9c75] Started GET "/robots.txt" for 95.215.163.71 at 2025-09-30 17:36:00 +0000
I, [2025-09-30T17:36:00.222268 #17]  INFO -- : [b1406399-cb0c-41e9-94aa-89c0dfea9c75] Processing by ErrorsController#unsupported_request as TEXT
I, [2025-09-30T17:36:00.222325 #17]  INFO -- : [b1406399-cb0c-41e9-94aa-89c0dfea9c75]   Parameters: {"any"=>"robots"}
I, [2025-09-30T17:36:00.222863 #17]  INFO -- : [b1406399-cb0c-41e9-94aa-89c0dfea9c75] Completed 200 OK in 0ms (Views: 0.2ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:36:00.355804 #17]  INFO -- : [07cb051c-8be6-4195-88c9-6a172f4195a4] Started GET "/favicon.ico" for 95.215.163.71 at 2025-09-30 17:36:00 +0000
I, [2025-09-30T17:36:00.356452 #17]  INFO -- : [07cb051c-8be6-4195-88c9-6a172f4195a4] Processing by ErrorsController#unsupported_request as */*
I, [2025-09-30T17:36:00.356502 #17]  INFO -- : [07cb051c-8be6-4195-88c9-6a172f4195a4]   Parameters: {"any"=>"favicon"}
I, [2025-09-30T17:36:00.356983 #17]  INFO -- : [07cb051c-8be6-4195-88c9-6a172f4195a4] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:39:51.978254 #17]  INFO -- : [991385a1-6a1b-4024-a762-bf6a417e182d] Started GET "/bigbluebutton/api/getRecordings?recordID=9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%2C9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703&state=any&checksum=32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922" for 88.135.68.1 at 2025-09-30 17:39:51 +0000
I, [2025-09-30T17:39:51.979503 #17]  INFO -- : [991385a1-6a1b-4024-a762-bf6a417e182d] Processing by BigBlueButtonApiController#get_recordings as XML
I, [2025-09-30T17:39:51.979560 #17]  INFO -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   Parameters: {"recordID"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990,9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703", "state"=>"any", "checksum"=>"32416a774053052b807cf38fc6a65c0e2b04b4a1c68b471fe5c5b883c82e3922"}
D, [2025-09-30T17:39:51.980307 #17] DEBUG -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   Rendering bigbluebutton_api/get_recordings.xml.builder
D, [2025-09-30T17:39:51.981801 #17] DEBUG -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   Recording Load (0.4ms)  SELECT DISTINCT "recordings".* FROM "recordings" LEFT OUTER JOIN "metadata" ON "metadata"."recording_id" = "recordings"."id" WHERE (record_id LIKE $1 ESCAPE '|' OR record_id LIKE $2 ESCAPE '|' OR record_id LIKE $3 ESCAPE '|' OR record_id LIKE $4 ESCAPE '|') ORDER BY "recordings"."starttime" DESC  [[nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759156916298%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990%"], [nil, "9f051a6205076e975327eed1f9de71f2c0e36bba-1759149372703%"]]
D, [2025-09-30T17:39:51.983290 #17] DEBUG -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   PlaybackFormat Load (0.4ms)  SELECT "playback_formats".* FROM "playback_formats" WHERE "playback_formats"."recording_id" IN ($1, $2, $3) ORDER BY "playback_formats"."format" ASC  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:39:51.984208 #17] DEBUG -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   Metadatum Load (0.4ms)  SELECT "metadata".* FROM "metadata" WHERE "metadata"."recording_id" IN ($1, $2, $3)  [["recording_id", 31], ["recording_id", 30], ["recording_id", 29]]
D, [2025-09-30T17:39:51.985572 #17] DEBUG -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   Thumbnail Load (0.2ms)  SELECT "thumbnails".* FROM "thumbnails" WHERE "thumbnails"."playback_format_id" IN ($1, $2, $3) ORDER BY "thumbnails"."sequence" ASC  [["playback_format_id", 29], ["playback_format_id", 30], ["playback_format_id", 31]]
I, [2025-09-30T17:39:51.989341 #17]  INFO -- : [991385a1-6a1b-4024-a762-bf6a417e182d]   Rendered bigbluebutton_api/get_recordings.xml.builder (Duration: 9.0ms | GC: 0.0ms)
I, [2025-09-30T17:39:51.989520 #17]  INFO -- : [991385a1-6a1b-4024-a762-bf6a417e182d] Completed 200 OK in 10ms (Views: 8.0ms | ActiveRecord: 1.4ms (4 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:39:52.028026 #17]  INFO -- : [14761c92-5259-400a-886e-7739e588e78d] Started GET "/recording/9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990/presentation?token=3a8e063a8a499cfd5ddbb6ab59a79940472a8dd4bb31acc75c7d2eedd37423a6" for 95.215.163.71 at 2025-09-30 17:39:52 +0000
I, [2025-09-30T17:39:52.028536 #17]  INFO -- : [14761c92-5259-400a-886e-7739e588e78d] Processing by PlaybackController#play as HTML
I, [2025-09-30T17:39:52.028578 #17]  INFO -- : [14761c92-5259-400a-886e-7739e588e78d]   Parameters: {"token"=>"3a8e063a8a499cfd5ddbb6ab59a79940472a8dd4bb31acc75c7d2eedd37423a6", "record_id"=>"9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990", "playback_format"=>"presentation"}
D, [2025-09-30T17:39:52.029626 #17] DEBUG -- : [14761c92-5259-400a-886e-7739e588e78d]   PlaybackFormat Load (0.2ms)  SELECT "playback_formats".* FROM "playback_formats" INNER JOIN "recordings" ON "recordings"."id" = "playback_formats"."recording_id" WHERE "playback_formats"."format" = $1 AND "recordings"."record_id" = $2 ORDER BY "playback_formats"."format" ASC LIMIT $3  [["format", "presentation"], ["record_id", "9f051a6205076e975327eed1f9de71f2c0e36bba-1759150237990"], ["LIMIT", 1]]
D, [2025-09-30T17:39:52.030407 #17] DEBUG -- : [14761c92-5259-400a-886e-7739e588e78d]   Recording Load (0.2ms)  SELECT "recordings".* FROM "recordings" WHERE "recordings"."id" = $1 LIMIT $2  [["id", 30], ["LIMIT", 1]]
D, [2025-09-30T17:39:52.031311 #17] DEBUG -- : [14761c92-5259-400a-886e-7739e588e78d]   Rendering errors/recording_not_found.html.erb
I, [2025-09-30T17:39:52.031504 #17]  INFO -- : [14761c92-5259-400a-886e-7739e588e78d]   Rendered errors/recording_not_found.html.erb (Duration: 0.1ms | GC: 0.0ms)
I, [2025-09-30T17:39:52.031656 #17]  INFO -- : [14761c92-5259-400a-886e-7739e588e78d] Completed 404 Not Found in 3ms (Views: 0.5ms | ActiveRecord: 0.4ms (2 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:39:52.603686 #17]  INFO -- : [02939be7-d607-4c90-842b-fa9ed689941e] Started GET "/robots.txt" for 95.215.163.71 at 2025-09-30 17:39:52 +0000
I, [2025-09-30T17:39:52.604277 #17]  INFO -- : [02939be7-d607-4c90-842b-fa9ed689941e] Processing by ErrorsController#unsupported_request as TEXT
I, [2025-09-30T17:39:52.604320 #17]  INFO -- : [02939be7-d607-4c90-842b-fa9ed689941e]   Parameters: {"any"=>"robots"}
I, [2025-09-30T17:39:52.604778 #17]  INFO -- : [02939be7-d607-4c90-842b-fa9ed689941e] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)
I, [2025-09-30T17:39:52.670557 #17]  INFO -- : [b485eeaa-4105-40e0-837b-f798444bd346] Started GET "/favicon.ico" for 95.215.163.71 at 2025-09-30 17:39:52 +0000
I, [2025-09-30T17:39:52.671075 #17]  INFO -- : [b485eeaa-4105-40e0-837b-f798444bd346] Processing by ErrorsController#unsupported_request as */*
I, [2025-09-30T17:39:52.671115 #17]  INFO -- : [b485eeaa-4105-40e0-837b-f798444bd346]   Parameters: {"any"=>"favicon"}
I, [2025-09-30T17:39:52.671528 #17]  INFO -- : [b485eeaa-4105-40e0-837b-f798444bd346] Completed 200 OK in 0ms (Views: 0.1ms | ActiveRecord: 0.0ms (0 queries, 0 cached) | GC: 0.0ms)

[morexzamani]

Hi everyone,

I went through the entire Scalelite installation process again, step by step, and carefully compared every configuration with the servers I had previously set up. Everything was exactly the same. I even switched branches between master and 1.0, but the issue persisted.

I also tested with a different Moodle instance, but still encountered the same “Recording Not Found” error.

After some investigation, I noticed that when the playback link works correctly, it redirects me to a URL like this:

https://example.com/playback/presentation/2.3/XXXXX50e163f04c3358ce6b9b76affa38-17593905XXXXX

But when the error occurs, it gets stuck on a page like this instead:

https://example.com/recording/XXXXX50e163f04c3358ce6b9b76affa38-17593905XXXXX/presentation?token=XXXXXXXXXXXa3b6c28ec77487a0f4cbe289dbf1c28d71XXXXXXXXXXXXXXX

Additionally, when I manually changed the protected column in the database to false, all recordings worked fine.
To make it consistent, I also had to set: PROTECTED_RECORDINGS_ENABLED=false in /etc/default/scalelite.

So now I’m wondering — what’s causing this random playback failure when PROTECTED_RECORDINGS_ENABLED is enabled?
It seems the issue is somehow related to protected recordings handling.

[Ithanil]

Maybe Moodle doesn't handle protected recordings correctly. Do you have a Greenlight frontend running to check if you see the same behavior there?

Please also make sure you fully understand what the "protected recordings" feature does: https://github.com/blindsidenetworks/scalelite/blob/master/docs/protectedrecordings_README.md

[morexzamani]

No, I tested the issue using another Moodle instance, and the problem persisted. However, when I used the same Moodle with a different Scalelite, the error did not occur.

Maybe Moodle doesn't handle protected recordings correctly. Do you have a Greenlight frontend running to check if you see the same behavior there?

Please also make sure you fully understand what the "protected recordings" feature does: https://github.com/blindsidenetworks/scalelite/blob/master/docs/protectedrecordings_README.md

[mariusros]

@morexzamani Meaning the other scalelite also had protected recordings and the issue did not appear?

I had to write a small plugin for our moodle instanced that deleted the recording from the moodle cache after someone clicked on it - that way we got a new URL with an unused token. Otherwise moodle seems to cache said link for up to 15 minutes, depending on the settings.

[morexzamani]

Meaning the other scalelite also had protected recordings and the issue did not appear?

Exactly. But I checked and compared their settings and didn't find any misconfiguration.
Did you have the problem of this topic with your Moodle that you developed a plugin for it?

[mariusros]

Yes, I had that problem with my moodle that I developed the plugin for.