https://livekit.io logo
Join Slack
Powered by
# announcements
  • c
    pulling now
  • c
    Copy code
    2021-01-31T22:28:43.850Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "JOINED", "participant": "PA_yqLwvuKFdv3qFRUmx7HZ57"}
2021-01-31T22:28:43.850Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "JOINED", "participant": "PA_yqLwvuKFdv3qFRUmx7HZ57", "oldState": "JOINING"}
2021-01-31T22:28:43.909Z        DEBUG   rtc/participant.go:124  ICE connection state changed    {"state": "connected"}
2021-01-31T22:28:43.909Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "ACTIVE", "participant": "PA_yqLwvuKFdv3qFRUmx7HZ57"}
2021-01-31T22:28:43.909Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "ACTIVE", "participant": "PA_yqLwvuKFdv3qFRUmx7HZ57", "oldState": "JOINED"}
2021-01-31T22:28:48.792Z        INFO    service/rtcservice.go:81        WS connection closed    {"participant": "0x7FEc008A88DC409C05D9D2565D933C680cd8eF77"}
2021-01-31T22:28:51.147Z        INFO    service/rtcservice.go:97        new client WS connected {"room": "RM_wewP5Ub5cYepggufrhNVx4", "roomName": "7846ddf8-38ba-4356-bf4d-fa9d8cfea645", "name": "0x7FEc008A88DC409C05D9D2565D933C680cd8eF77"}
2021-01-31T22:28:51.148Z        DEBUG   service/roommanager.go:231      RTC session finishing   {"participant": "0x7FEc008A88DC409C05D9D2565D933C680cd8eF77", "room": "7846ddf8-38ba-4356-bf4d-fa9d8cfea645"}
2021-01-31T22:28:51.148Z        DEBUG   service/roommanager.go:171      starting RTC session    {"room": "7846ddf8-38ba-4356-bf4d-fa9d8cfea645", "node": "ND_0000000000000000", "participant": "0x7FEc008A88DC409C05D9D2565D933C680cd8eF77", "num_participants": 1}
2021-01-31T22:28:51.148Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "DISCONNECTED", "participant": "PA_yqLwvuKFdv3qFRUmx7HZ57"}
2021-01-31T22:28:51.148Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "DISCONNECTED", "participant": "PA_yqLwvuKFdv3qFRUmx7HZ57", "oldState": "ACTIVE"}
2021-01-31T22:28:51.148Z        INFO    rtc/room.go:128 new participant joined  {"id": "PA_6ivXvTTyawfMBQ2j7QLUFG", "identity": "0x7FEc008A88DC409C05D9D2565D933C680cd8eF77", "roomId": "RM_wewP5Ub5cYepggufrhNVx4"}
2021-01-31T22:28:51.148Z        DEBUG   rtc/participant.go:124  ICE connection state changed    {"state": "closed"}
2021-01-31T22:28:51.156Z        DEBUG   rtc/participant.go:213  answering       {"state": "JOINING"}
2021-01-31T22:28:51.156Z        DEBUG   rtc/participant.go:124  ICE connection state changed    {"state": "checking"}
2021-01-31T22:28:51.156Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "JOINED", "participant": "PA_6ivXvTTyawfMBQ2j7QLUFG"}
2021-01-31T22:28:51.156Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "JOINED", "participant": "PA_6ivXvTTyawfMBQ2j7QLUFG", "oldState": "JOINING"}
2021-01-31T22:28:51.228Z        DEBUG   rtc/participant.go:124  ICE connection state changed    {"state": "connected"}
2021-01-31T22:28:51.228Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "ACTIVE", "participant": "PA_6ivXvTTyawfMBQ2j7QLUFG"}
2021-01-31T22:28:51.228Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "ACTIVE", "participant": "PA_6ivXvTTyawfMBQ2j7QLUFG", "oldState": "JOINED"}
2021-01-31T22:29:35.080Z        INFO    service/rtcservice.go:81        WS connection closed    {"participant": "0xe9bd998CEEBFeEeAa666639A642115f63997458c"}
2021-01-31T22:29:38.260Z        INFO    service/rtcservice.go:97        new client WS connected {"room": "RM_wewP5Ub5cYepggufrhNVx4", "roomName": "7846ddf8-38ba-4356-bf4d-fa9d8cfea645", "name": "0xe9bd998CEEBFeEeAa666639A642115f63997458c"}
2021-01-31T22:29:38.260Z        DEBUG   service/roommanager.go:171      starting RTC session    {"room": "7846ddf8-38ba-4356-bf4d-fa9d8cfea645", "node": "ND_0000000000000000", "participant": "0xe9bd998CEEBFeEeAa666639A642115f63997458c", "num_participants": 1}
2021-01-31T22:29:38.260Z        DEBUG   service/roommanager.go:231      RTC session finishing   {"participant": "0xe9bd998CEEBFeEeAa666639A642115f63997458c", "room": "7846ddf8-38ba-4356-bf4d-fa9d8cfea645"}
2021-01-31T22:29:38.260Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "DISCONNECTED", "participant": "PA_QgyYidFwVCTCRAMv2XcFzh"}
2021-01-31T22:29:38.260Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "DISCONNECTED", "participant": "PA_QgyYidFwVCTCRAMv2XcFzh", "oldState": "ACTIVE"}
2021-01-31T22:29:38.261Z        INFO    rtc/room.go:128 new participant joined  {"id": "PA_uc3cdaAgaH4PUNKtDDmhcQ", "identity": "0xe9bd998CEEBFeEeAa666639A642115f63997458c", "roomId": "RM_wewP5Ub5cYepggufrhNVx4"}
2021-01-31T22:29:38.261Z        DEBUG   rtc/participant.go:124  ICE connection state changed    {"state": "closed"}
2021-01-31T22:29:38.261Z        DEBUG   rtc/participant.go:499  updating participant state      {"state": "DISCONNECTED", "participant": "PA_uc3cdaAgaH4PUNKtDDmhcQ"}
2021-01-31T22:29:38.261Z        DEBUG   rtc/room.go:101 participant state changed       {"state": "DISCONNECTED", "participant": "PA_uc3cdaAgaH4PUNKtDDmhcQ", "oldState": "JOINING"}
2021-01-31T22:29:38.261Z        ERROR   service/rtcservice.go:140       error reading from websocket    {"error": "read tcp 127.0.0.1:7880->127.0.0.1:43466: use of closed network connection"}
<http://github.com/livekit/livekit-server/pkg/service.(*RTCService).ServeHTTP|github.com/livekit/livekit-server/pkg/service.(*RTCService).ServeHTTP>
        /home/furqan/develop/livekit-server/pkg/service/rtcservice.go:140
net/http.(*ServeMux).ServeHTTP
        /usr/local/go/src/net/http/server.go:2417
<http://github.com/urfave/negroni.Wrap.func1|github.com/urfave/negroni.Wrap.func1>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/negroni.go:46
<http://github.com/urfave/negroni.HandlerFunc.ServeHTTP|github.com/urfave/negroni.HandlerFunc.ServeHTTP>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/negroni.go:29
<http://github.com/urfave/negroni.middleware.ServeHTTP|github.com/urfave/negroni.middleware.ServeHTTP>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/negroni.go:38
net/http.HandlerFunc.ServeHTTP
        /usr/local/go/src/net/http/server.go:2042
<http://github.com/livekit/livekit-server/pkg/service.(*APIKeyAuthMiddleware).ServeHTTP|github.com/livekit/livekit-server/pkg/service.(*APIKeyAuthMiddleware).ServeHTTP>
        /home/furqan/develop/livekit-server/pkg/service/auth.go:76
<http://github.com/urfave/negroni.middleware.ServeHTTP|github.com/urfave/negroni.middleware.ServeHTTP>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/negroni.go:38
<http://github.com/urfave/negroni.(*Recovery).ServeHTTP|github.com/urfave/negroni.(*Recovery).ServeHTTP>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/recovery.go:193
<http://github.com/urfave/negroni.middleware.ServeHTTP|github.com/urfave/negroni.middleware.ServeHTTP>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/negroni.go:38
<http://github.com/urfave/negroni.(*Negroni).ServeHTTP|github.com/urfave/negroni.(*Negroni).ServeHTTP>
        /home/furqan/go/pkg/mod/github.com/urfave/negroni@v1.0.0/negroni.go:96
net/http.serverHandler.ServeHTTP
        /usr/local/go/src/net/http/server.go:2843
net/http.(*conn).serve
        /usr/local/go/src/net/http/server.go:1925
  • c
    still got the WS closing but other cases seem to be better
  • c
    that's what happens in the "WS is CLOSED OR CLOSING" state
  • d
    what is the repro steps?
  • d
    also.. is this multi-node or single? I haven't been able to repro with redis based backend
  • c
    multi node: load page -> connect to stream (succes) -> refresh N times -> it'll happen pretty much always before 5 refreshes
  • d
    ah ok. that's helpful. thanks
  • c
    after this connect stuff the next things (i think) are: onTrackSubscribed (spotty, works ~75% time) onTrackUnsubscribed (doesn't work, publisher fires unpublishTracks) could be on our end but mentioning them
  • d
    kk lemme know what you find. it'll be helpful to share client & server logs when that happens.
  • d
    any repro steps for the above? or is it just refresh?
  • d
    anyone listening to elon on clubhouse?
  • d
    6k limit per room ... *tsk *tsk..
  • d
    FYI, I can repro the issue of not getting subscribe in some cases. so it's not on your end
    đź‘Ť 1
  • c
    Yeah 6k limit is interesting probably some limit on their app side
  • d
    ok, some progress on the issues: 1. TrackSubscribed not sent sometimes - I believe this is due to a timing issue on the server side, fixed in latest 2. TrackUnsubscribed not sent when remote participant disconnect - fixed in 0.1.3 in client sdk 3. 
    WS is CLOSED OR CLOSING
    this happens very rarely.. only if I refresh the browser repeatedly within a second of the page loading. I'm not seeing anything on the server side that would cause this... no good ideas as to where the issue is
  • c
    sweet
  • c
    i’ll try out 1 and 2 in a bit
    đź‘Ť 1
  • s
    This message was deleted.
    d
    c
    • 3
    • 3
  • c
    I’m still seeing #1 issue happen, steps to reproduce: user 1 + 2 connect to call user 1 calls in (user 2 gets onTrackSubscribed) user 2 calls in (user 1 gets nothing)
  • d
    the calls in means publish?
  • c
    yeah sorry calls in == publish
  • c
    onTrackUnsubscribed it working now though!
    âś… 1
  • s
    This message was deleted.
    c
    d
    • 3
    • 17
  • c
    i’ll get that for you
  • d
    thx 🙏
  • s
    This message was deleted.
    c
    d
    w
    • 4
    • 90
  • s
    This message was deleted.
    đź‘€ 1
    c
    • 2
    • 3
  • c
    Good news the latest commits makes 2 person calls work again for us! There’s a couple new bugs that popped up now, i think i’ll move the issues to github so that we can be more organized?
  • d
    hey yeah that sounds good! I'll use PRs to track these fixes going forward too
1...567...46Latest