FFProbe Timeout

[amirdt22]

I think this is a bug, but it's also my first time trying this so it's probably me.

I'm running on an intel mac.

Briefly, the most basic config file and a public RTSP stream result in the error message:

[2023-03-07 18:35:20] [ERROR   ] [viseron.components.ffmpeg.stream.camera_1] - Retrying None in 30.0 seconds as it raised FFprobeTimeout: FFprobe command timed out after 90s.
[2023-03-07 18:36:05] [WARNING ] [viseron.components] - Domain nvr for component nvr with identifier camera_1 is still waiting for dependencies: ['domain: camera, identifier: camera_1']

However the ffprobe command does work

$ ffprobe -hide_banner -loglevel error -print_format json -show_error -show_streams rtsp://wowzaec2demo.streamlock.net:554/vod/mp4:BigBuckBunny_115k.mp4
{
    "streams": [
....

Configs and Logs

docker-compose.yml

version: "2.4"

services:
  viseron:
    image: roflcoopter/viseron:latest
    container_name: viseron
    #network_mode: "host"
    volumes:
      - /Users/amir/viseron/recordings:/recordings
      - /Users/amir/viseron/config:/config
      - /etc/localtime:/etc/localtime:ro
    ports:
      - 8888:8888

config.yml

## Start by adding some cameras
ffmpeg:
  camera:
    camera_1:  # This value has to be unique across all cameras
      name: rtsp_sample
      host: wowzaec2demo.streamlock.net #rtsp://wowzaec2demo.streamlock.net/vod/mp4:BigBuckBunny_115k.mp4
      port: 554
      path: '/vod/mp4:BigBuckBunny_115k.mp4'


## To tie everything together we need to configure one more component.
nvr:
  camera_1:  # Run NVR for camera_1

logger:
  default_level: debug

# Now you can restart Viseron and you should be good to go!

debug logs

[2023-03-07 18:39:05] [INFO    ] [viseron.core] - -------------------------------------------
[2023-03-07 18:39:05] [INFO    ] [viseron.core] - Initializing Viseron
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up component logger
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setup of component logger took 0.0 seconds
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up component data_stream
[2023-03-07 18:39:05] [DEBUG   ] [viseron.watchdog] - Registering <RestartableThread(data_stream, initial daemon)> in the watchdog
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setup of component data_stream took 0.0 seconds
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up component webserver
[2023-03-07 18:39:05] [DEBUG   ] [asyncio] - Using selector: EpollSelector
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/domain/registered/camera, <bound method WebServer.camera_registered of <WebServer(Tornado WebServer, initial daemon)>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic viseron/signal/shutdown, <bound method WebServer.stop of <WebServer(Tornado WebServer, initial daemon)>>
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setup of component webserver took 0.0 seconds
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up component ffmpeg
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up component nvr
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setup of component nvr took 0.0 seconds
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setup of component ffmpeg took 0.0 seconds
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up domain camera for component ffmpeg with identifier camera_1
[2023-03-07 18:39:05] [INFO    ] [viseron.components] - Setting up domain nvr for component nvr with identifier camera_1
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components] - Domain nvr for component nvr with identifier camera_1 will wait for dependencies ['domain: camera, identifier: camera_1']
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Adding entity rtsp_sample Connected from component ffmpeg
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/camera/status, <bound method ConnectionStatusBinarySensor.handle_event of <viseron.domains.camera.entity.binary_sensor.ConnectionStatusBinarySensor object at 0x7fd46845c610>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Setting state of binary_sensor.camera_1_connected to state: off, attributes {'name': 'rtsp_sample Connected', 'domain': 'binary_sensor'}
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Adding entity rtsp_sample Connection from component ffmpeg
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/camera/start, <bound method CameraConnectionToggle.handle_start_event of <viseron.domains.camera.entity.toggle.CameraConnectionToggle object at 0x7fd46845c850>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/camera/stop, <bound method CameraConnectionToggle.handle_stop_event of <viseron.domains.camera.entity.toggle.CameraConnectionToggle object at 0x7fd46845c850>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/camera/started, <bound method CameraConnectionToggle.handle_started_stopped_event of <viseron.domains.camera.entity.toggle.CameraConnectionToggle object at 0x7fd46845c850>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/camera/stopped, <bound method CameraConnectionToggle.handle_started_stopped_event of <viseron.domains.camera.entity.toggle.CameraConnectionToggle object at 0x7fd46845c850>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Setting state of toggle.camera_1_connection to state: off, attributes {'name': 'rtsp_sample Connection', 'domain': 'toggle'}
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.ffmpeg.recorder.camera_1] - Running cleanup
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Adding entity rtsp_sample Recorder from component ffmpeg
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/recorder/start, <bound method RecorderBinarySensor.handle_start_event of <viseron.domains.camera.entity.binary_sensor.RecorderBinarySensor object at 0x7fd468476040>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/recorder/stop, <bound method RecorderBinarySensor.handle_stop_event of <viseron.domains.camera.entity.binary_sensor.RecorderBinarySensor object at 0x7fd468476040>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Setting state of binary_sensor.camera_1_recorder to state: off, attributes {'name': 'rtsp_sample Recorder', 'domain': 'binary_sensor'}
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Adding entity rtsp_sample Latest Thumbnail from component ffmpeg
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/camera_1/recorder/start, <bound method ThumbnailImage.handle_event of <viseron.domains.camera.entity.image.ThumbnailImage object at 0x7fd468476700>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.states] - Setting state of image.camera_1_latest_thumbnail to state: unknown, attributes {'name': 'rtsp_sample Latest Thumbnail', 'domain': 'image', 'start_time': None, 'path': None, 'thumbnail_path': None}
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.ffmpeg.recorder.camera_1] - Initializing recorder
[2023-03-07 18:39:05] [DEBUG   ] [viseron.helpers] - Creating folder /segments/camera_1
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic viseron/signal/shutdown, <bound method SegmentCleanup.shutdown of <viseron.components.ffmpeg.segments.SegmentCleanup object at 0x7fd468476190>>
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.ffmpeg.camera.camera_1] - Initializing camera rtsp_sample
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.ffmpeg.stream.camera_1] - Getting stream information for rtsp://wowzaec2demo.streamlock.net:554/vod/mp4:BigBuckBunny_115k.mp4
[2023-03-07 18:39:05] [DEBUG   ] [viseron.components.ffmpeg.stream.camera_1] - FFprobe command: ffprobe -hide_banner -loglevel error -print_format json -show_error -show_streams rtsp://wowzaec2demo.streamlock.net:554/vod/mp4:BigBuckBunny_115k.mp4
[2023-03-07 18:39:07] [DEBUG   ] [viseron.components.webserver.websocket_api] - WebSocket opened <viseron.components.webserver.websocket_api.WebSocketHandler object at 0x7fd46847dd30>
[2023-03-07 18:39:07] [DEBUG   ] [viseron.components.webserver.websocket_api] - Received {"type":"get_cameras","command_id":1}
[2023-03-07 18:39:07] [DEBUG   ] [viseron.components.webserver.websocket_api] - Received {"type":"subscribe_event","event":"domain/registered/camera","command_id":2}
[2023-03-07 18:39:07] [DEBUG   ] [viseron.components.webserver.websocket_api] - Received {"type":"subscribe_event","event":"*/recorder/complete","command_id":3}
[2023-03-07 18:39:07] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/domain/registered/camera, <function subscribe_event.<locals>.forward_event at 0x7fd468479d30>
[2023-03-07 18:39:07] [DEBUG   ] [viseron.components.data_stream] - Subscribing to data topic event/*/recorder/complete, <function subscribe_event.<locals>.forward_event at 0x7fd468479c10>
[2023-03-07 18:39:20] [ERROR   ] [viseron.components.ffmpeg.stream.camera_1] - Retrying None in 2.0 seconds as it raised FFprobeTimeout: FFprobe command timed out after 15s.
[2023-03-07 18:39:25] [WARNING ] [viseron.components] - Setup of domain camera for component ffmpeg with identifier camera_1 is taking longer than 20 seconds
[2023-03-07 18:39:38] [DEBUG   ] [viseron.components.webserver.websocket_api] - Received {"type":"ping","command_id":4}
[2023-03-07 18:39:52] [ERROR   ] [viseron.components.ffmpeg.stream.camera_1] - Retrying None in 4.0 seconds as it raised FFprobeTimeout: FFprobe command timed out after 30s.

What else I've tried

• my local rtsp stream from a dahua camera
• the default config file that includes darknet and motion detection
• opening the rtsp streams in VLC and verifying they work