in a 100 calls I have gathered logs of call 69th
{"level":"INFO","ts":"2021-10-23T17:02:11.220Z","logger":"goroutine: 69","caller":"janus/tear.go:6","msg":"inititate janus"}
{"level":"INFO","ts":"2021-10-23T17:02:12.510Z","logger":"goroutine: 69","caller":"janus/tear.go:9","msg":"read RTCP packets"}
{"level":"INFO","ts":"2021-10-23T17:02:12.510Z","logger":"goroutine: 69","caller":"janus/tear.go:13","msg":"stream Audio File"}
{"level":"INFO","ts":"2021-10-23T17:02:12.510Z","logger":"goroutine: 69","caller":"janus/tear.go:17","msg":"create and set local offer"}
{"level":"INFO","ts":"2021-10-23T17:02:13.306Z","logger":"goroutine: 69","caller":"janus/tear.go:20","msg":"start call"}
{"level":"INFO","ts":"2021-10-23T17:02:13.603Z","logger":"goroutine: 69","caller":"janus/call.go:20","msg":"room created","room":6256690406467622}
{"level":"INFO","ts":"2021-10-23T17:02:14.105Z","logger":"goroutine: 69","caller":"janus/call.go:30","msg":"joined to room","id":8117065589170429,"participants":[]}
{"level":"INFO","ts":"2021-10-23T17:02:14.816Z","logger":"goroutine: 69","caller":"janus/call.go:43","msg":"offer has been sent"}
{"level":"INFO","ts":"2021-10-23T17:02:15.111Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"checking"}
{"level":"INFO","ts":"2021-10-23T17:02:16.812Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"connected"}
{"level":"INFO","ts":"2021-10-23T17:02:18.115Z","logger":"goroutine: 69","caller":"janus/janus.go:141","msg":"WebRTCUp","id":4436228422124936}
{"level":"INFO","ts":"2021-10-23T17:02:18.320Z","logger":"goroutine: 69","caller":"janus/persist.go:13","msg":"track has started","payload_type":111,"mime_type":"audio/opus"}
{"level":"INFO","ts":"2021-10-23T17:02:23.314Z","logger":"goroutine: 69","caller":"janus/janus.go:139","msg":"MediaEvent","type":"audio","receiving":true}
{"level":"INFO","ts":"2021-10-23T17:02:33.317Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"disconnected"}
{"level":"INFO","ts":"2021-10-23T17:02:38.013Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"connected"}
{"level":"INFO","ts":"2021-10-23T17:02:40.417Z","logger":"goroutine: 69","caller":"janus/janus.go:139","msg":"MediaEvent","type":"audio","receiving":true}
{"level":"INFO","ts":"2021-10-23T17:02:40.520Z","logger":"goroutine: 69","caller":"janus/janus.go:139","msg":"MediaEvent","type":"audio","receiving":false}
{"level":"INFO","ts":"2021-10-23T17:02:46.205Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"disconnected"}
{"level":"INFO","ts":"2021-10-23T17:02:48.209Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"connected"}
{"level":"INFO","ts":"2021-10-23T17:03:22.612Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"disconnected"}
{"level":"INFO","ts":"2021-10-23T17:03:47.011Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"failed"}
{"level":"INFO","ts":"2021-10-23T17:05:26.539Z","logger":"goroutine: 69","caller":"client/stream.go:48","msg":"all audio pages parsed and sent"}
{"level":"INFO","ts":"2021-10-23T17:05:26.540Z","logger":"goroutine: 69","caller":"janus/janus.go:125","msg":"connection state has changed","state":"closed"}
as you can see we connected twice, means:
connected in 2021-10-23T17:02:16.812Z
, disconnected in 2021-10-23T17:02:33.317Z
and again:
connected in 2021-10-23T17:02:38.013Z
and disconnected in 2021-10-23T17:02:46.205Z
and again:
connected in 2021-10-23T17:02:48.209Z
and disconnected in 2021-10-23T17:03:22.612Z
it may be a source of problem, I think it worth if we take a look at.
the sum of durations is 59 seconds but provided audio has length of 74 seconds