Facing problem to generate recording

[Jibon Costa]

Hello,

I am facing problem to generate recording in one of my server. BigBlueButton Server 2.2.14 (1964)

Log: /var/log/bigbluebutton/presentation/process-183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639.log

I, [2020-05-25T04:47:13.326236 #23645]  INFO -- : Executing: ffmpeg -y -v warning -nostats -max_error_rate 1.0 -filter_complex color=c=white:s=640x480:r=24,trim=end=0.010 -an -codec mpeg2video -q:v 2 -g 240 -pix_fmt yuv420p -r 24 -f mpegts -
I, [2020-05-25T04:47:13.326260 #23645]  INFO -- : Sending output to #<File:0x00000001c1ed68>
I, [2020-05-25T04:47:13.339758 #23645]  INFO -- : Output file is empty, nothing was encoded (check -ss / -t / -frames parameters if used)
I, [2020-05-25T04:47:13.341209 #23645]  INFO -- : Exit status: 0
I, [2020-05-25T04:47:13.341272 #23645]  INFO -- : Performing video encode pass 0
I, [2020-05-25T04:47:13.353544 #23645]  INFO -- : Executing: ffmpeg -y -v warning -nostats -max_error_rate 1.0 -i /var/bigbluebutton/recording/process/presentation/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/webcams.ts -i /var/bigbluebutton/recording/process/presentation/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/recording.flac -c:v libvpx-vp9 -b:v 750K -minrate 375K -maxrate 1088K -crf 33 -quality good -speed 1 -g 240 -tile-columns 1 -threads 2 -c:a libopus -b:a 48K -f webm -passlogfile /var/bigbluebutton/recording/process/presentation/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/webcams /var/bigbluebutton/recording/process/presentation/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/webcams.encode.webm
I, [2020-05-25T04:47:13.365786 #23645]  INFO -- : /var/bigbluebutton/recording/process/presentation/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/webcams.ts: Invalid data found when processing input
I, [2020-05-25T04:47:13.366548 #23645]  INFO -- : Exit status: 1
E, [2020-05-25T04:47:13.366603 #23645] ERROR -- : ffmpeg failed, exit code 1
E, [2020-05-25T04:47:13.366646 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/edl.rb:50:in `block (2 levels) in encode'
E, [2020-05-25T04:47:13.366663 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/edl.rb:48:in `chdir'
E, [2020-05-25T04:47:13.366681 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/edl.rb:48:in `block in encode'
E, [2020-05-25T04:47:13.366695 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/edl.rb:32:in `each'
E, [2020-05-25T04:47:13.366708 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/edl.rb:32:in `each_with_index'
E, [2020-05-25T04:47:13.366722 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/edl.rb:32:in `encode'
E, [2020-05-25T04:47:13.366736 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/generators/video.rb:88:in `block in process_webcam_videos'
E, [2020-05-25T04:47:13.366749 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/generators/video.rb:87:in `each'
E, [2020-05-25T04:47:13.366763 #23645] ERROR -- : /usr/local/bigbluebutton/core/lib/recordandplayback/generators/video.rb:87:in `process_webcam_videos'
E, [2020-05-25T04:47:13.366776 #23645] ERROR -- : process/presentation.rb:231:in `<main>'

Log: /var/log/bigbluebutton/archive-183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639.log

# Logfile created on 2020-05-25 04:45:48 +0200 by logger.rb/v1.2.7
I, [2020-05-25T04:45:48.315577 #9876]  INFO -- : Archiving events for 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.319887 #9876]  INFO -- : Reordered event timestamp=1882669668 module=PARTICIPANT eventname=AssignPresenterEvent by 1 positions
I, [2020-05-25T04:45:48.321199 #9876]  INFO -- : Reordered event timestamp=1882669675 module=PARTICIPANT eventname=AssignPresenterEvent by 2 positions
I, [2020-05-25T04:45:48.332376 #9876]  INFO -- : Reordered event timestamp=1882674320 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 3 positions
I, [2020-05-25T04:45:48.339329 #9876]  INFO -- : Reordered event timestamp=1882676521 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.340938 #9876]  INFO -- : Reordered event timestamp=1882676529 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.343141 #9876]  INFO -- : Reordered event timestamp=1882676872 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.343841 #9876]  INFO -- : Reordered event timestamp=1882676873 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 2 positions
I, [2020-05-25T04:45:48.345509 #9876]  INFO -- : Reordered event timestamp=1882677028 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.346383 #9876]  INFO -- : Reordered event timestamp=1882677172 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.352301 #9876]  INFO -- : Reordered event timestamp=1882677359 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 3 positions
I, [2020-05-25T04:45:48.355891 #9876]  INFO -- : Reordered event timestamp=1882677712 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 2 positions
I, [2020-05-25T04:45:48.357565 #9876]  INFO -- : Reordered event timestamp=1882677720 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 2 positions
I, [2020-05-25T04:45:48.360244 #9876]  INFO -- : Reordered event timestamp=1882679013 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.361653 #9876]  INFO -- : Reordered event timestamp=1882679042 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.362520 #9876]  INFO -- : Reordered event timestamp=1882679080 module=WHITEBOARD eventname=WhiteboardCursorMoveEvent by 1 positions
I, [2020-05-25T04:45:48.371446 #9876]  INFO -- : Archiving audio /var/freeswitch/meetings/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639-*.*
W, [2020-05-25T04:45:48.371535 #9876]  WARN -- : No audio found for 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.371548 #9876]  INFO -- : Archiving notes for 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.371583 #9876]  INFO -- : Downloading http://localhost:9001/p/f24af1ef/export/txt to /var/bigbluebutton/recording/raw/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/notes/tmp_note.txt
E, [2020-05-25T04:45:48.389066 #9876] ERROR -- : Failed to download file: File not available: Not Found
I, [2020-05-25T04:45:48.389155 #9876]  INFO -- : Notes were not used in 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.389185 #9876]  INFO -- : Archiving contents of /var/bigbluebutton/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.389251 #9876]  INFO -- : Executing: rsync -rstv /var/bigbluebutton/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/ /var/bigbluebutton/recording/raw/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/presentation/
I, [2020-05-25T04:45:48.390065 #9876]  INFO -- : sending incremental file list
I, [2020-05-25T04:45:48.390349 #9876]  INFO -- : ./
I, [2020-05-25T04:45:48.390552 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/
I, [2020-05-25T04:45:48.390578 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660.pdf
I, [2020-05-25T04:45:48.390625 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/svgs/
I, [2020-05-25T04:45:48.390640 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/svgs/slide1.svg
I, [2020-05-25T04:45:48.390699 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/svgs/slide2.svg
I, [2020-05-25T04:45:48.390769 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/svgs/slide3.svg
I, [2020-05-25T04:45:48.390844 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/svgs/slide4.svg
I, [2020-05-25T04:45:48.390907 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/svgs/slide5.svg
I, [2020-05-25T04:45:48.391067 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/textfiles/
I, [2020-05-25T04:45:48.391087 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/textfiles/slide-1.txt
I, [2020-05-25T04:45:48.391100 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/textfiles/slide-2.txt
I, [2020-05-25T04:45:48.391115 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/textfiles/slide-3.txt
I, [2020-05-25T04:45:48.391130 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/textfiles/slide-4.txt
I, [2020-05-25T04:45:48.391143 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/textfiles/slide-5.txt
I, [2020-05-25T04:45:48.391155 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/thumbnails/
I, [2020-05-25T04:45:48.391166 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/thumbnails/thumb-1.png
I, [2020-05-25T04:45:48.391178 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/thumbnails/thumb-2.png
I, [2020-05-25T04:45:48.391189 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/thumbnails/thumb-3.png
I, [2020-05-25T04:45:48.391201 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/thumbnails/thumb-4.png
I, [2020-05-25T04:45:48.391214 #9876]  INFO -- : 39ee3fe22522bf9980c1dd99126c690c4ae74957-1590374719660/thumbnails/thumb-5.png
I, [2020-05-25T04:45:48.432199 #9876]  INFO -- :
I, [2020-05-25T04:45:48.432234 #9876]  INFO -- : sent 130,906 bytes  received 347 bytes  262,506.00 bytes/sec
I, [2020-05-25T04:45:48.432248 #9876]  INFO -- : total size is 129,610  speedup is 0.99
I, [2020-05-25T04:45:48.432317 #9876]  INFO -- : Exit status: 0
I, [2020-05-25T04:45:48.432338 #9876]  INFO -- : Archiving contents of /usr/share/red5/webapps/screenshare/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.432401 #9876]  INFO -- : Executing: rsync -rstv /usr/share/red5/webapps/screenshare/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/ /var/bigbluebutton/recording/raw/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/deskshare/
I, [2020-05-25T04:45:48.433130 #9876]  INFO -- : sending incremental file list
I, [2020-05-25T04:45:48.433157 #9876]  INFO -- : rsync: change_dir "/usr/share/red5/webapps/screenshare/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639" failed: No such file or directory (2)
I, [2020-05-25T04:45:48.573887 #9876]  INFO -- :
I, [2020-05-25T04:45:48.573937 #9876]  INFO -- : sent 20 bytes  received 12 bytes  64.00 bytes/sec
I, [2020-05-25T04:45:48.573953 #9876]  INFO -- : total size is 0  speedup is 0.00
I, [2020-05-25T04:45:48.573966 #9876]  INFO -- : rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1183) [sender=3.1.1]
I, [2020-05-25T04:45:48.574030 #9876]  INFO -- : Exit status: 23
W, [2020-05-25T04:45:48.574049 #9876]  WARN -- : Failed to archive contents of /usr/share/red5/webapps/screenshare/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.574065 #9876]  INFO -- : Archiving contents of /usr/share/red5/webapps/video/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.574236 #9876]  INFO -- : Executing: rsync -rstv /usr/share/red5/webapps/video/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/ /var/bigbluebutton/recording/raw/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/video/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/
I, [2020-05-25T04:45:48.575020 #9876]  INFO -- : sending incremental file list
I, [2020-05-25T04:45:48.575046 #9876]  INFO -- : rsync: change_dir "/usr/share/red5/webapps/video/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639" failed: No such file or directory (2)
I, [2020-05-25T04:45:48.696825 #9876]  INFO -- :
I, [2020-05-25T04:45:48.696867 #9876]  INFO -- : sent 20 bytes  received 12 bytes  64.00 bytes/sec
I, [2020-05-25T04:45:48.696883 #9876]  INFO -- : total size is 0  speedup is 0.00
I, [2020-05-25T04:45:48.696895 #9876]  INFO -- : rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1183) [sender=3.1.1]
I, [2020-05-25T04:45:48.696945 #9876]  INFO -- : Exit status: 23
W, [2020-05-25T04:45:48.696963 #9876]  WARN -- : Failed to archive contents of /usr/share/red5/webapps/video/streams/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.696979 #9876]  INFO -- : Archiving contents of /var/kurento/screenshare/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.697054 #9876]  INFO -- : Executing: rsync -rstv /var/kurento/screenshare/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/ /var/bigbluebutton/recording/raw/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/deskshare/
I, [2020-05-25T04:45:48.697936 #9876]  INFO -- : sending incremental file list
I, [2020-05-25T04:45:48.697970 #9876]  INFO -- : rsync: change_dir "/var/kurento/screenshare/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639" failed: No such file or directory (2)
I, [2020-05-25T04:45:48.838685 #9876]  INFO -- :
I, [2020-05-25T04:45:48.838722 #9876]  INFO -- : sent 20 bytes  received 12 bytes  64.00 bytes/sec
I, [2020-05-25T04:45:48.838738 #9876]  INFO -- : total size is 0  speedup is 0.00
I, [2020-05-25T04:45:48.838751 #9876]  INFO -- : rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1183) [sender=3.1.1]
I, [2020-05-25T04:45:48.838799 #9876]  INFO -- : Exit status: 23
W, [2020-05-25T04:45:48.838816 #9876]  WARN -- : Failed to archive contents of /var/kurento/screenshare/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.838832 #9876]  INFO -- : Archiving contents of /var/kurento/recordings/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.838876 #9876]  INFO -- : Executing: rsync -rstv /var/kurento/recordings/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/ /var/bigbluebutton/recording/raw/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/video/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639/
I, [2020-05-25T04:45:48.839685 #9876]  INFO -- : sending incremental file list
I, [2020-05-25T04:45:48.839712 #9876]  INFO -- : rsync: change_dir "/var/kurento/recordings/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639" failed: No such file or directory (2)
I, [2020-05-25T04:45:48.980614 #9876]  INFO -- :
I, [2020-05-25T04:45:48.980656 #9876]  INFO -- : sent 20 bytes  received 12 bytes  64.00 bytes/sec
I, [2020-05-25T04:45:48.980672 #9876]  INFO -- : total size is 0  speedup is 0.00
I, [2020-05-25T04:45:48.980685 #9876]  INFO -- : rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1183) [sender=3.1.1]
I, [2020-05-25T04:45:48.980732 #9876]  INFO -- : Exit status: 23
W, [2020-05-25T04:45:48.980751 #9876]  WARN -- : Failed to archive contents of /var/kurento/recordings/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.980765 #9876]  INFO -- : Deleting originals of archived media files.
I, [2020-05-25T04:45:48.980779 #9876]  INFO -- : Deleting audio /var/freeswitch/meetings/183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639-*.*
I, [2020-05-25T04:45:48.980962 #9876]  INFO -- : Fetching the recording marks for 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639.
I, [2020-05-25T04:45:48.984834 #9876]  INFO -- : Segment start: 1882665739, end: 1882682713
I, [2020-05-25T04:45:48.984869 #9876]  INFO -- : Checking for recording marks for 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639 segment
I, [2020-05-25T04:45:48.984886 #9876]  INFO -- : Getting start and stop rec button events
I, [2020-05-25T04:45:48.984899 #9876]  INFO -- : Getting record status events
I, [2020-05-25T04:45:48.985724 #9876]  INFO -- : Matching record events
I, [2020-05-25T04:45:48.985750 #9876]  INFO -- : Recording marks found: true

Any idea why it's failing? Thanks in advance

[Jibon Costa]

/var/log/bigbluebutton/bbb-rap-worker.log

tail -n 1000 /var/log/bigbluebutton/bbb-rap-worker.log | grep 183f0bf3a0982a127bdb8161e0c44eb696b3e75c
I, [2020-05-25T04:45:48.179278 #9871]  INFO -- : Executing: ruby archive/archive.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:45:48.995533 #9871]  INFO -- : Successfully archived 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:46:19.171559 #14531]  INFO -- : Executing: ruby sanity/sanity.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:46:19.323771 #14531]  INFO -- : Successfully sanity checked 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:47:11.326144 #23637]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:47:11.555454 #23637]  INFO -- : Executing: ruby process/presentation.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:47:13.382362 #23637]  INFO -- : Process format presentation failed for 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639 break
I, [2020-05-25T04:48:19.168870 #24028]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:49:19.167134 #24045]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:50:19.167538 #24059]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:51:19.168241 #24533]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:52:19.174787 #24646]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:53:19.172911 #24663]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:54:19.167538 #24678]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:55:19.164984 #24701]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:56:19.168012 #24716]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:57:19.166785 #24730]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:58:19.166693 #24746]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T04:59:19.166892 #24761]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T05:00:19.166712 #29999]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T05:01:19.167430 #30027]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T05:02:19.171113 #30100]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T05:03:19.168588 #30116]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639
I, [2020-05-25T05:04:19.167710 #30173]  INFO -- : Executing: ruby utils/captions.rb -m 183f0bf3a0982a127bdb8161e0c44eb696b3e75c-1590374719639