search

l3tnun / EPGStation

Mirakurun を使用した録画管理ソフト
https://twitter.com/l3tnun
MIT License
547 stars 148 forks source link

[報告] イベントリレー動作確認 #683

Closed b00t0x closed 3 months ago

b00t0x commented 3 months ago

※ issue ではないので任意にクローズいただいて構いません

環境

Report

イベントリレーの動作が確認できたので報告させていただきます。

以下の 5/30 の tvk プロ野球中継について、1. の録画予約（非ルール、手動）のみで 2. まで録画され、3. は録画されませんでした。

  1. 18:00-18:15 に tvk2 のサブチャンネル放送 ( tvk 1はニュース、事前に EPG あり )
  2. 18:15-21:30 で tvk1 でリレー中継 ( 事前に EPG あり )
  3. 21:30- 終了まで tvk2 でリレー中継 ( EPG 出現せず )

3. についてですが、開始後も番組表に出てこず、mirakc を共有している KonomiTV や市販テレビでさえもチャンネル送りで tvk2 に切り替えできなかったので、イベントリレーの不発というより視聴環境固有の問題かもしれません。アンテナ受信でなく J:COM で映っているチャンネルなので、放送波自体にリレー中継が載らなかったような気がしています。 （ログが event relay program is not found. となっていたので、番組的にはリレー中継を案内しつつそれが EPG に存在しないという感じでロジックは期待通り動いているように見えます）

ログはこんな感じでした。他にあった方が良い情報があればお知らせください。

$ sudo cat volumes/epgstation/logs/Operator/system.log | grep T18:1
[2024-05-30T18:14:29.285] [INFO] system - all reservation update start
[2024-05-30T18:14:29.290] [INFO] system - update reservation: 58
[2024-05-30T18:14:29.298] [INFO] system - no update reservation: 58
[2024-05-30T18:14:29.308] [INFO] system - update rule reservation: 1
[2024-05-30T18:14:29.371] [INFO] system - { insert: 0, update: 0, delete: 0 }
[2024-05-30T18:14:29.372] [INFO] system - successful update rule reservation: 1
[2024-05-30T18:14:29.382] [INFO] system - all reservation update finish
[2024-05-30T18:14:40.046] [INFO] system - set event relay program. programId 323752463310583 -> 323752463217450
[2024-05-30T18:14:40.046] [INFO] system - add event relay. reserveId: 58, programId: 323752463217450
[2024-05-30T18:14:40.063] [INFO] system - successful add event relay. reserveId: 58, newReserveId: 64 programId: 323752463217450
[2024-05-30T18:14:40.064] [INFO] system - set timer: 64, 4936
[2024-05-30T18:14:45.000] [INFO] system - preprec: 64
[2024-05-30T18:15:01.018] [ERROR] system - preprec failed: 64
[2024-05-30T18:15:01.018] [ERROR] system - Error: Bad status respond (408 Request Timeout).
[2024-05-30T18:15:03.049] [INFO] system - start recEnd reserveId: 58 recordedId: 39
[2024-05-30T18:15:03.049] [INFO] system - stop drop check: /app/drop/2024-05-30 1800 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts.log
[2024-05-30T18:15:03.050] [INFO] system - remove recording flag: 39
[2024-05-30T18:15:03.073] [INFO] system - move file: /app/records/recording/2024-05-30 1800 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts -> /app/records/recorded/2024-05-30 1800 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T18:15:03.080] [INFO] system - update file size: 39
[2024-05-30T18:15:03.081] [INFO] system - { recordedId: 39, error: 1, drop: 0, scrambling: 0 }
[2024-05-30T18:15:03.090] [INFO] system - emit finish recording reserveId: 58, recordedId: 39, isNeedDeleteReservation: true
[2024-05-30T18:15:03.091] [INFO] system - add thumbnail queue: 39
[2024-05-30T18:15:03.091] [INFO] system - recording finish reserveId: 58, recordedId: 39, videoFileFulPath: /app/records/recorded/2024-05-30 1800 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T18:15:03.091] [INFO] system - cancel reservation: 58
[2024-05-30T18:15:03.112] [INFO] system - { insert: 0, update: 0, delete: 1 }
[2024-05-30T18:15:03.118] [INFO] system - successful cancel reservation: 58
[2024-05-30T18:15:03.310] [INFO] system - create thumbnail: 39, /app/thumbnail/39.jpg
[2024-05-30T18:15:06.021] [INFO] system - preprec: 64
[2024-05-30T18:15:07.371] [INFO] system - recording: 64 /app/records/recording/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T18:15:07.376] [INFO] system - add drop log file: /app/drop/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts.log
[2024-05-30T18:15:07.397] [INFO] system - add recorded 64 /app/records/recording/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T18:15:07.405] [INFO] system - recording added reserveId: 64, recordedId: 40
[2024-05-30T18:15:07.405] [INFO] system - create video file: 2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T18:15:07.410] [INFO] system - set stream.finished: reserveId: 64 recordedId: 40

$ sudo cat volumes/epgstation/logs/Operator/system.log | grep 'T21:[23]'
[2024-05-30T21:24:29.773] [INFO] system - all reservation update start
[2024-05-30T21:24:29.778] [INFO] system - update reservation: 64
[2024-05-30T21:24:29.784] [INFO] system - no update reservation: 64
[2024-05-30T21:24:29.795] [INFO] system - update rule reservation: 1
[2024-05-30T21:24:29.856] [INFO] system - { insert: 0, update: 0, delete: 0 }
[2024-05-30T21:24:29.856] [INFO] system - successful update rule reservation: 1
[2024-05-30T21:24:29.867] [INFO] system - all reservation update finish
[2024-05-30T21:29:40.047] [WARN] system - event relay program is not found. networkId: networkId: 32375, serviceId: 24633, eventId: 10642
[2024-05-30T21:30:03.016] [INFO] system - start recEnd reserveId: 64 recordedId: 40
[2024-05-30T21:30:03.017] [INFO] system - stop drop check: /app/drop/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts.log
[2024-05-30T21:30:03.017] [INFO] system - remove recording flag: 40
[2024-05-30T21:30:03.055] [INFO] system - move file: /app/records/recording/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts -> /app/records/recorded/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T21:30:03.063] [INFO] system - update file size: 40
[2024-05-30T21:30:03.064] [INFO] system - { recordedId: 40, error: 0, drop: 0, scrambling: 0 }
[2024-05-30T21:30:03.069] [INFO] system - emit finish recording reserveId: 64, recordedId: 40, isNeedDeleteReservation: true
[2024-05-30T21:30:03.069] [INFO] system - add thumbnail queue: 40
[2024-05-30T21:30:03.069] [INFO] system - recording finish reserveId: 64, recordedId: 40, videoFileFulPath: /app/records/recorded/2024-05-30 1815 tvkプロ野球中継横浜DeNAベイスターズ熱烈LIVE「横浜×楽天」[多].m2ts
[2024-05-30T21:30:03.069] [INFO] system - cancel reservation: 64
[2024-05-30T21:30:03.076] [INFO] system - { insert: 0, update: 0, delete: 1 }
[2024-05-30T21:30:03.085] [INFO] system - successful cancel reservation: 64
[2024-05-30T21:30:03.224] [INFO] system - create thumbnail: 40, /app/thumbnail/40.jpg
[2024-05-30T21:34:29.808] [INFO] system - all reservation update start
[2024-05-30T21:34:29.814] [INFO] system - update rule reservation: 1
[2024-05-30T21:34:29.877] [INFO] system - { insert: 0, update: 0, delete: 0 }
[2024-05-30T21:34:29.878] [INFO] system - successful update rule reservation: 1
[2024-05-30T21:34:29.888] [INFO] system - all reservation update finish
l3tnun commented 3 months ago

早速動作確認していただけるとは、ありがとうございます。 ログの添付していただけて大変助かりました。 概ね想定通り機能していることが確認でき、ひとまず安心いたしました。

  1. についてなのですが、以下の部分が該当する部分でして、 DBに保存された情報を使用してリレー先のプログラムが存在するのか確認をしています。

https://github.com/l3tnun/EPGStation/blob/cb46e8335bbdea756e605547eb8786f0bda3044e/src/model/operator/recording/RecorderModel.ts#L998-L1009

（ログが event relay program is not found. となっていたので、番組的にはリレー中継を案内しつつそれが EPG に存在しないという感じでロジックは期待通り動いているように見えます）

そのとおりでして、一つだけ懸念があるとすればDBへの記録が間に合わず、Mirakurun/mirakc上にはリレー先の番組情報が存在するものの、EPGStation 側が検知できないことですね。

633 のおかげでほぼ10秒程度の遅延(Mirakurun/mirakcと比較して)で DB に反映されるためほとんど問題ないと考えています。

ただ、3のような状況でリレー先が直前に EPG 上に出現しリレーに失敗しないかについてはなんとも言えないため、しばらく運用して様子を見ようかなと思っています。 今回の動作についてはEPGに出現せずとのことでしたので、EPGStationとしては意図通りの動作となります。

この issue 関しては、ある程度運用して問題なさそうなことが確認できましたら master へ反映してクローズさせていいただきます。

l3tnun commented 3 months ago

一通りイベントリレーで確認したい動作が確認できましたでの、version 2.10.0としてリリースしました。 そのため、このissueはクローズさせていいただきます。

@b00t0x ご協力ありがとうございました。