Local testing with >2 clients broken

I am going to keep it short, since i already tracked it down so much:
I am currently unable to test locally with 2 or more clients:
the settings of the VRC SDK during testing

I can use 1 client completely normal, but the other one will not join and instead try to join my homeworld: (which it can’t because it would be public and i am in “local testing” mode)

What i thought could be the issue:

  • The Scene. I tried using the sample scenes from the VRC SDK, this did change nothing.
  • The Unity project. I tried projects, which i know that work completely normal when published, and i still have the same issue.
  • Something not being up-to-date. I have double-checked the Base and World SDK, they are up-to-date (3.1.10)
  • There being too much stuff in the Scene. I have created a scene with literally just the VRC_SceneDescriptor and a Cube as collider, nothing changed.

As i do not want to upload my logfiles publicly, here is the summary of the logfile contents:

Logfile description

The first error that occurs is this:

2023.02.11 12:53:22 Log        -  [Always] Get VRChat Subscription Details! Subscription Id:vrchatplus-monthly active:True desc:VRChat Plus (Monthly)
2023.02.11 12:53:22 Log        -  [Behaviour] Has Active Subscription
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null
2023.02.11 12:53:22 Error      -  [API] ApiFeedback: Could not write property due to decoding failure, wanted SystemInt32 for contentVersion
null

Then it begins doing Avatar API requests. One of them fails with a 404: avtr_53188085-46cb-49cf-8cc0-42ee75195ee3. Using the API i was unable to find this avatar ID anywhere in my favourites. But as the stream continues afther that, i don’t think this is relevant.

2023.02.11 12:53:22 Log        -  [API] [28] Sending Get request to https://api.vrchat.cloud/api/1/avatars/avtr_53188085-46cb-49cf-8cc0-42ee75195ee3?apiKey=<api-key-replaced>&organization=vrchat
2023.02.11 12:53:23 Error      -  [API] [28, 404, Get, 2] Request Finished with Error!
No Exception
{{"error":{{"message":"Can't find avatarǃ","status_code":404}}}}
2023.02.11 12:53:23 Log        -  [API] ApiAvatar:avtr_53188085-46cb-49cf-8cc0-42ee75195ee3: 
Can't find avatarǃ
2023.02.11 12:53:23 Error      -  [API] [28, 404, Get, -1] Abandoning request, because - Can't find avatarǃ

Next up it tries to load users (again - the real logfile contains these entries highly scrambled, but i’m trying to group them)

2023.02.11 12:53:22 Log        -  [API] Requesting Get users/usr_5a4a47b1-666c-4b6c-9e8a-3a754d94a6d4 {{}} disableCache: False retryCount: 2
2023.02.11 12:53:23 Log        -  [API] [52] Sending Get request to https://api.vrchat.cloud/api/1/users/usr_5a4a47b1-666c-4b6c-9e8a-3a754d94a6d4?apiKey=<api-key-replaced>&organization=vrchat
<in the logfile this is exactly where the 404 occurs from before>
2023.02.11 12:53:23 Log        -  [API] APIUser:usr_5a4a47b1-666c-4b6c-9e8a-3a754d94a6d4: 
Response was missing username.
2023.02.11 12:53:26 Log        -  [API] [52, 200, Get, 1] Retrying request, because - Response was missing username.
2023.02.11 12:53:26 Log        -  [API] APIUser:usr_5a4a47b1-666c-4b6c-9e8a-3a754d94a6d4: 
Response was missing username.
2023.02.11 12:53:26 Error      -  [API] [52, 200, Get, 0] Abandoning request, because - Response was missing username.

This seems to happen for every request accessing the /api/1/users/ endpoint (Request-ID 52-151;158-174;197-199;215) except for request 7, 227 & 258, which are my own ID.

That might be unrelated, but:

2023.02.11 12:53:27 Warning    -  [EOSManager] [Warning][LogEOSAnalytics] EOS SDK Analytics disabled for route [1].

It is sucessfully connecting to the WebSocket API, starting to get notifications. The responses for the invalid API calls to /api/1/users/ are still coming in.
The local world beings to load.

2023.02.11 12:53:32 Log        -  [Behaviour] Instantiating VRC_OBJECTS
2023.02.11 12:53:32 Warning    -  The referenced script on this Behaviour (Game Object '<null>') is missing!
UnityEngine.Object:Instantiate(Object, Vector3, Quaternion)
ÎÍÌÎÍÍÍÍÎÍÏÍÌÍÌÏÍÏÎÎÎÎÎ:ÎÎÎÍÌÍÏÏÏÍÎÍÍÌÎÎÌÍÏÎÍÏÍ(Object, Vector3, Quaternion, Boolean, Boolean, Boolean)
ÎÍÌÎÍÍÍÍÎÍÏÍÌÍÌÏÍÏÎÎÎÎÎ:ÎÎÎÍÌÍÏÏÏÍÎÍÍÌÎÎÌÍÏÎÍÏÍ(Object, Boolean, Boolean, Boolean)
ÍÏÏÍÌÍÏÎÍÍÌÍÎÍÌÏÏÍÏÍÍÍÎ:ÏÍÌÍÎÎÍÎÌÌÏÏÏÏÏÌÍÌÌÎÏÏÌ()
ÎÌÍÌÏÌÎÍÎÏÌÍÎÎÍÍÏÍÎÏÏÎÏ:MoveNext()
UnityEngine.SetupCoroutine:InvokeMoveNext(IEnumerator, IntPtr)
2023.02.11 12:53:32 Warning    -  The referenced script on this Behaviour (Game Object 'USpeak') is missing!
UnityEngine.Object:Instantiate(Object, Vector3, Quaternion)
ÎÍÌÎÍÍÍÍÎÍÏÍÌÍÌÏÍÏÎÎÎÎÎ:ÎÎÎÍÌÍÏÏÏÍÎÍÍÌÎÎÌÍÏÎÍÏÍ(Object, Vector3, Quaternion, Boolean, Boolean, Boolean)
ÎÍÌÎÍÍÍÍÎÍÏÍÌÍÌÏÍÏÎÎÎÎÎ:ÎÎÎÍÌÍÏÏÏÍÎÍÍÌÎÎÌÍÏÎÍÏÍ(Object, Boolean, Boolean, Boolean)
ÍÏÏÍÌÍÏÎÍÍÌÍÎÍÌÏÏÍÏÍÍÍÎ:ÏÍÌÍÎÎÍÎÌÌÏÏÏÏÏÌÍÌÌÎÏÏÌ()
ÎÌÍÌÏÌÎÍÎÏÌÍÎÎÍÍÏÍÎÏÏÎÏ:MoveNext()
UnityEngine.SetupCoroutine:InvokeMoveNext(IEnumerator, IntPtr)
2023.02.11 12:53:32 Warning    -  The referenced script on this Behaviour (Game Object 'USpeak') is missing!
UnityEngine.Object:Instantiate(Object, Vector3, Quaternion)
ÎÍÌÎÍÍÍÍÎÍÏÍÌÍÌÏÍÏÎÎÎÎÎ:ÎÎÎÍÌÍÏÏÏÍÎÍÍÌÎÎÌÍÏÎÍÏÍ(Object, Vector3, Quaternion, Boolean, Boolean, Boolean)
ÎÍÌÎÍÍÍÍÎÍÏÍÌÍÌÏÍÏÎÎÎÎÎ:ÎÎÎÍÌÍÏÏÏÍÎÍÍÌÎÎÌÍÏÎÍÏÍ(Object, Boolean, Boolean, Boolean)
ÍÏÏÍÌÍÏÎÍÍÌÍÎÍÌÏÏÍÏÍÍÍÎ:ÏÍÌÍÎÎÍÎÌÌÏÏÏÏÏÌÍÌÌÎÏÏÌ()
ÎÌÍÌÏÌÎÍÎÏÌÍÎÎÍÍÏÍÎÏÏÎÏ:MoveNext()
UnityEngine.SetupCoroutine:InvokeMoveNext(IEnumerator, IntPtr)
2023.02.11 12:53:32 Log        -  [Behaviour] Hard max is 0
2023.02.11 12:53:32 Log        -  [Behaviour] Spent 0.002757072s initializing VRC Objects

Seems to be unrelated, the world continues to load.

2023.02.11 12:53:32 Log        -  [Behaviour] Spent 0.1630421s processing scene objects.
2023.02.11 12:53:32 Log        -  [Behaviour] Fixing materials
2023.02.11 12:53:32 Log        -  [Behaviour] Spent 6.198883E-05s fixing materials.
2023.02.11 12:53:32 Log        -  [Behaviour] Clamp audio sources
2023.02.11 12:53:32 Log        -  [Behaviour] Finalizing scene
2023.02.11 12:53:32 Log        -  [Behaviour] Spent 0.005972862s finalizing scene.
2023.02.11 12:53:32 Log        -  [Behaviour] Switching to network region eu
2023.02.11 12:53:32 Log        -  [Behaviour] Connecting to realtime network
(...)
2023.02.11 12:53:34 Log        -  [Behaviour] Spent 0.0008201599s entering world.
2023.02.11 12:53:34 Log        -  [Behaviour] Waiting to enter network room

Now it gets interesting:

2023.02.11 12:53:35 Error      -  [Network Transport] Discarding event of type 20 because there was no valid originating player with ActorNr 0.

I don’t know what an “event of type 20” is, but the ActorNr 0 (according to his logs) is the working client. “there was no valid originating player with ActorNr 0” - uh oh.

Working client: ActorNr 0
Not working client ActorNr 1

An UdonBehaviour exception occurs. Note: This is a map from the examples, all UdonBehaviours in this map are from VRC itself.
2023.02.11 12:53:36 Error      -  [UdonBehaviour] An exception occurred during Udon execution, this UdonBehaviour will be halted.
VRC.Udon.VM.UdonVMException: The VM encountered an error!
Exception Message:
  Function '__Blit__UnityEngineTexture_UnityEngineRenderTexture_UnityEngineMaterial__SystemVoid' is not implemented yet
----------------------
Program Counter was at: 3616
----------------------
Stack Dump:
  0: 0x00000099
  1: 0x0000009A
  2: 0x0000009B
----------------------
Heap Dump:
  0x00000000: 0
  0x00000001: 0
  0x00000002: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000003: _SetupCamera
  0x00000004: _SetupCamera
  0x00000005: Capture Camera (UnityEngine.Camera)
  0x00000006: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000007: _UpdateSettings
  0x00000008: False
  0x00000009: False
  0x0000000A: False
  0x0000000B: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x0000000C: _ShowOthersOn
  0x0000000D: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x0000000E: _MapUpdateLoop
  0x0000000F: Capture Camera (UnityEngine.Camera)
  0x00000010: False
  0x00000011: Capture Camera (UnityEngine.Camera)
  0x00000012: SceneCaptureRT (UnityEngine.RenderTexture)
  0x00000013: Capture Camera (UnityEngine.Camera)
  0x00000014: 75.85
  0x00000015: 2
  0x00000016: Capture Camera (UnityEngine.Camera)
  0x00000017: Capture Camera (UnityEngine.Camera)
  0x00000018: 8388407
  0x00000019: UnityEngine.LayerMask
  0x0000001A: MiniMap Blitter (UnityEngine.Material)
  0x0000001B: _CameraMatrix
  0x0000001C: 0.00000	0.00000	0.00000	0.00000
0.00000	0.00000	0.00000	0.00000
0.00000	0.00000	0.00000	0.00000
0.00000	0.00000	0.00000	0.00000

  0x0000001D: MiniMap Blitter (UnityEngine.Material)
  0x0000001E: _CameraSize
  0x0000001F: 0
  0x00000020: MiniMap Blitter (UnityEngine.Material)
  0x00000021: _PlayerDotColor
  0x00000022: RGBA(0.000, 0.000, 0.000, 0.000)
  0x00000023: MiniMap Blitter (UnityEngine.Material)
  0x00000024: _PlayerDotSize
  0x00000025: 0
  0x00000026: MiniMap Blitter (UnityEngine.Material)
  0x00000027: _OtherPlayersDotColor
  0x00000028: RGBA(0.000, 0.000, 0.000, 0.000)
  0x00000029: MiniMap Blitter (UnityEngine.Material)
  0x0000002A: _OtherPlayersDotSize
  0x0000002B: 0
  0x0000002C: False
  0x0000002D: null
  0x0000002E: _ShowOthers
  0x0000002F: 1
  0x00000030: MiniMap Blitter (UnityEngine.Material)
  0x00000031: _ShowOthers
  0x00000032: 0
  0x00000033: False
  0x00000034: MiniMap Blitter (UnityEngine.Material)
  0x00000035: _FollowPlayer
  0x00000036: 1
  0x00000037: null
  0x00000038: _FollowPlayer
  0x00000039: 0
  0x0000003A: MiniMap Blitter (UnityEngine.Material)
  0x0000003B: _MaxPlayers
  0x0000003C: 0
  0x0000003D: MiniMap Blitter (UnityEngine.Material)
  0x0000003E: _ZoomLevel
  0x0000003F: 0
  0x00000040: False
  0x00000041: null
  0x00000042: SHOW_OTHERS
  0x00000043: MiniMap Blitter (UnityEngine.Material)
  0x00000044: SHOW_OTHERS
  0x00000045: False
  0x00000046: True
  0x00000047: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000048: _UpdateSettings
  0x00000049: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x0000004A: _OtherPlayersLoop
  0x0000004B: False
  0x0000004C: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x0000004D: _UpdateSettings
  0x0000004E: False
  0x0000004F: False
  0x00000050: False
  0x00000051: False
  0x00000052: False
  0x00000053: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000054: _UpdateSettings
  0x00000055: False
  0x00000056: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000057: _OtherPlayersLoop
  0x00000058: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000059: _SetupCamera
  0x0000005A: null
  0x0000005B: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x0000005C: _UpdateSettings
  0x0000005D: False
  0x0000005E: 0
  0x0000005F: False
  0x00000060: False
  0x00000061: False
  0x00000062: null
  0x00000063: False
  0x00000064: False
  0x00000065: null
  0x00000066: null
  0x00000067: 0
  0x00000068: null
  0x00000069: 0
  0x0000006A: (0.0, 0.0, 0.0, 0.0)
  0x0000006B: 0
  0x0000006C: 0
  0x0000006D: 0
  0x0000006E: 1
  0x0000006F: (0.0, 0.0, 0.0)
  0x00000070: (0.0, 0.0, 0.0)
  0x00000071: (0.0, 0.0, 0.0)
  0x00000072: null
  0x00000073: null
  0x00000074: 0
  0x00000075: 0
  0x00000076: 0
  0x00000077: 0
  0x00000078: 0
  0x00000079: 1
  0x0000007A: 0
  0x0000007B: 0
  0x0000007C: 0
  0x0000007D: 0
  0x0000007E: 0
  0x0000007F: 0
  0x00000080: 0
  0x00000081: 0
  0x00000082: 0
  0x00000083: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x00000084: _OtherPlayersLoop
  0x00000085: 0
  0x00000086: Update
  0x00000087: True
  0x00000088: MiniMap Graph (UnityEngine.GameObject)
  0x00000089: MiniMap Graph (UnityEngine.GameObject)
  0x0000008A: MiniMap Blitter (UnityEngine.Material)
  0x0000008B: _PlayerPos
  0x0000008C: (0.0, 0.1, -2.5, 0.0)
  0x0000008D: 0
  0x0000008E: 0.05799991
  0x0000008F: -2.537
  0x00000090: 0
  0x00000091: (0.0, 0.1, -2.5)
  0x00000092: (0.0, 0.1, -2.5)
  0x00000093: (0.0, 0.1, -2.5)
  0x00000094: VRC.SDKBase.VRCPlayerApi
  0x00000095: False
  0x00000096: null
  0x00000097: _PlayerPositions
  0x00000098: null
  0x00000099: SceneCaptureRT (UnityEngine.RenderTexture)
  0x0000009A: MinimapRT (UnityEngine.RenderTexture)
  0x0000009B: MiniMap Blitter (UnityEngine.Material)
  0x0000009C: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x0000009D: _MapUpdateLoop
  0x0000009E: 0
  0x0000009F: Update
  0x000000A0: MiniMap Graph (VRC.Udon.UdonBehaviour)
  0x000000A1: _MapUpdateLoop
  0x000000A2: 0
  0x000000A3: Update
  0x000000A4: 0
  0x000000A5: False
  0x000000A6: False
  0x000000A7: False
  0x000000A8: False
  0x000000A9: null
  0x000000AA: null
  0x000000AB: 0
  0x000000AC: null
  0x000000AD: 0
  0x000000AE: null
  0x000000AF: 0
  0x000000B0: 0
  0x000000B1: 1
  0x000000B2: null
  0x000000B3: null
  0x000000B4: True
  0x000000B5: False
  0x000000B6: 0
  0x000000B7: False
  0x000000B8: False
  0x000000B9: False
  0x000000BA: null
  0x000000BB: null
  0x000000BC: null
  0x000000BD: 0
  0x000000BE: null
  0x000000BF: null
  0x000000C0: 0
  0x000000C1: null
  0x000000C2: null
  0x000000C3: null
  0x000000C4: 0
  0x000000C5: (0.0, 0.0, 0.0, 0.0)
  0x000000C6: 0
  0x000000C7: 0
  0x000000C8: 1
  0x000000C9: null
  0x000000CA: 0
  0x000000CB: 0
  0x000000CC: 0
  0x000000CD: 0
  0x000000CE: (0.0, 0.0, 0.0, 0.0)
  0x000000CF: (0.0, 0.0, 0.0, 0.0)
  0x000000D0: (0.0, 0.0, 0.0, 0.0)
  0x000000D1: null
  0x000000D2: 0
  0x000000D3: True
  0x000000D4: False
  0x000000D5: UnityEngine.LayerMask
  0x000000D6: MinimapRT (UnityEngine.RenderTexture)
  0x000000D7: 82
  0x000000D8: True
  0x000000D9: False
  0x000000DA: RGBA(0.131, 0.378, 1.000, 1.000)
  0x000000DB: 1
  0x000000DC: RGBA(0.203, 1.000, 0.203, 1.000)
  0x000000DD: 0.8
  0x000000DE: True
  0x000000DF: 2
  0x000000E0: 4
  0x000000E1: 1
  0x000000E2: 1
  0x000000E3: Capture Camera (UnityEngine.Camera)
  0x000000E4: SceneCaptureRT (UnityEngine.RenderTexture)
  0x000000E5: MiniMap Blitter (UnityEngine.Material)
  0x000000E6: VRC.SDKBase.VRCPlayerApi
  0x000000E7: 1.00000	0.00000	-0.00001	0.00000
0.00001	0.00000	1.00000	0.00001
0.00000	1.00000	0.00000	-100.00000
0.00000	0.00000	0.00000	1.00000

  0x000000E8: 151.7
  0x000000E9: False
  0x000000EA: VRC.SDKBase.VRCPlayerApi[]
  0x000000EB: UnityEngine.Vector4[]
  0x000000EC: null
  0x000000ED: 0
  0x000000EE: False
  0x000000EF: False
  0x000000F0: VRCSDKBaseNetworking.__get_LocalPlayer__VRCSDKBaseVRCPlayerApi
  0x000000F1: VRCSDKBaseVRCPlayerApiArray.__ctor__SystemInt32__VRCSDKBaseVRCPlayerApiArray
  0x000000F2: UnityEngineVector4Array.__ctor__SystemInt32__UnityEngineVector4Array
  0x000000F3: VRCUdonCommonInterfacesIUdonEventReceiver.__SendCustomEvent__SystemString__SystemVoid
  0x000000F4: UnityEngineCamera.__Render__SystemVoid
  0x000000F5: SystemBoolean.__op_ConditionalAnd__SystemBoolean_SystemBoolean__SystemBoolean
  0x000000F6: UnityEngineCamera.__set_enabled__SystemBoolean__SystemVoid
  0x000000F7: UnityEngineCamera.__set_targetTexture__UnityEngineRenderTexture__SystemVoid
  0x000000F8: UnityEngineCamera.__get_worldToCameraMatrix__UnityEngineMatrix4x4
  0x000000F9: UnityEngineCamera.__get_orthographicSize__SystemSingle
  0x000000FA: SystemSingle.__op_Multiplication__SystemSingle_SystemSingle__SystemSingle
  0x000000FB: UnityEngineLayerMask.__get_value__SystemInt32
  0x000000FC: UnityEngineCamera.__set_cullingMask__SystemInt32__SystemVoid
  0x000000FD: UnityEngineMaterial.__SetMatrix__SystemString_UnityEngineMatrix4x4__SystemVoid
  0x000000FE: UnityEngineMaterial.__SetFloat__SystemString_SystemSingle__SystemVoid
  0x000000FF: UnityEngineMaterial.__SetColor__SystemString_UnityEngineColor__SystemVoid
  0x00000100: UnityEngineMaterial.__SetInt__SystemString_SystemInt32__SystemVoid
  0x00000101: UnityEngineMaterial.__EnableKeyword__SystemString__SystemVoid
  0x00000102: UnityEngineMaterial.__DisableKeyword__SystemString__SystemVoid
  0x00000103: SystemBoolean.__op_UnaryNegation__SystemBoolean__SystemBoolean
  0x00000104: SystemBoolean.__op_ConditionalOr__SystemBoolean_SystemBoolean__SystemBoolean
  0x00000105: SystemInt32.__op_Subtraction__SystemInt32_SystemInt32__SystemInt32
  0x00000106: UnityEngineMathf.__Clamp__SystemInt32_SystemInt32_SystemInt32__SystemInt32
  0x00000107: SystemInt32.__op_LessThan__SystemInt32_SystemInt32__SystemBoolean
  0x00000108: SystemInt32.__op_Addition__SystemInt32_SystemInt32__SystemInt32
  0x00000109: VRCSDKBaseVRCPlayerApiArray.__Get__SystemInt32__VRCSDKBaseVRCPlayerApi
  0x0000010A: VRCSDKBaseUtilities.__IsValid__SystemObject__SystemBoolean
  0x0000010B: VRCSDKBaseVRCPlayerApi.__get_isLocal__SystemBoolean
  0x0000010C: VRCSDKBaseVRCPlayerApi.__GetPosition__UnityEngineVector3
  0x0000010D: UnityEngineVector3.__get_x__SystemSingle
  0x0000010E: UnityEngineVector3.__get_y__SystemSingle
  0x0000010F: UnityEngineVector3.__get_z__SystemSingle
  0x00000110: UnityEngineVector4.__ctor__SystemSingle_SystemSingle_SystemSingle_SystemSingle__UnityEngineVector4
  0x00000111: UnityEngineVector4Array.__Set__SystemInt32_UnityEngineVector4__SystemVoid
  0x00000112: SystemInt32.__op_Remainder__SystemInt32_SystemInt32__SystemInt32
  0x00000113: VRCUdonCommonInterfacesIUdonEventReceiver.__SendCustomEventDelayedFrames__SystemString_SystemInt32_VRCUdonCommonEnumsEventTiming__SystemVoid
  0x00000114: UnityEngineGameObject.__get_activeSelf__SystemBoolean
  0x00000115: UnityEngineMaterial.__SetVector__SystemString_UnityEngineVector4__SystemVoid
  0x00000116: UnityEngineMaterial.__SetVectorArray__SystemString_UnityEngineVector4Array__SystemVoid
  0x00000117: VRCSDKBaseVRCGraphics.__Blit__UnityEngineTexture_UnityEngineRenderTexture_UnityEngineMaterial__SystemVoid
  0x00000118: VRCSDKBaseVRCPlayerApiArray.__get_Length__SystemInt32
  0x00000119: VRCSDKBaseVRCPlayerApiArray.__Set__SystemInt32_VRCSDKBaseVRCPlayerApi__SystemVoid
  0x0000011A: VRCSDKBaseVRCPlayerApi.__Equals__SystemObject__SystemBoolean
  0x0000011B: UnityEngineVector4Array.__Get__SystemInt32__UnityEngineVector4
  0x0000011C: UnityEngineVector4.__get_x__SystemSingle
  0x0000011D: UnityEngineVector4.__get_y__SystemSingle
  0x0000011E: UnityEngineVector4.__get_z__SystemSingle
----------------------
Inner Exception:
 ---> System.NotSupportedException: Function '__Blit__UnityEngineTexture_UnityEngineRenderTexture_UnityEngineMaterial__SystemVoid' is not implemented yet
  at VRC.Udon.Wrapper.Modules.ExternVRCSDKBaseVRCGraphics.GetExternFunctionDelegate (System.String externFunctionSignature) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.Wrapper.UdonWrapper.GetExternFunctionDelegate (System.String externFunctionSignature) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.VM.UdonVM.Interpret () [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunProgram (System.UInt32 entryPoint) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunProgram (System.String eventName) [0x00000] in <00000000000000000000000000000000>:0 
  at UnityEngine.Events.UnityAction`2[T0,T1].Invoke (T0 arg0, T1 arg1) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.VM.UdonVM.Interpret () [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunProgram (System.UInt32 entryPoint) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunEvent (System.String eventName, System.ValueTuple`2[System.String,System.Object][] programVariables) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.ManagedUpdate () [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonManager.Update () [0x00000] in <00000000000000000000000000000000>:0 
   --- End of inner exception stack trace ---
  at VRC.Udon.VM.UdonVM.Interpret () [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunProgram (System.UInt32 entryPoint) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunProgram (System.String eventName) [0x00000] in <00000000000000000000000000000000>:0 
  at UnityEngine.Events.UnityAction`2[T0,T1].Invoke (T0 arg0, T1 arg1) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.VM.UdonVM.Interpret () [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunProgram (System.UInt32 entryPoint) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.RunEvent (System.String eventName, System.ValueTuple`2[System.String,System.Object][] programVariables) [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonBehaviour.ManagedUpdate () [0x00000] in <00000000000000000000000000000000>:0 
  at VRC.Udon.UdonManager.Update () [0x00000] in <00000000000000000000000000000000>:0 
Then it begins loading the 10 images of the 'QM Banners'
2023.02.11 12:53:36 Log        -  IPSs 'QM Banners':
2023.02.11 12:53:36 Log        -  {{"createdAt":"2022-11-24T00:43:14.0000000+01:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/bfbd6fdbc44669e6cc54b5d396866457be11b4ec2f8419d99dd1ec128b20689b.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://hello.vrchat.com/blog/vrchat-groups"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"a78116248c69099f9a12a4ee41a86749", "id":"qm-banner-groups", "isEnabled":true, "priority":910, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-12-08T05:07:57.0000000+01:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2022-11-16T23:30:26.0000000+01:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/3a0274c0957823de563debb0a86acece661d839209a1658ff2d71da48c9a6c8c.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://vcc.docs.vrchat.com/guides/getting-started/"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"456230a82d667b45db628539881868c5", "id":"qm-banner-vcc", "isEnabled":true, "priority":920, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-11-16T23:44:59.0000000+01:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2022-10-13T02:27:36.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/bb59f934efd62472d87e87bfba547cd1d89d637fab85d8dc82a3cd0c5eb077ea.png", "isNew":false, "onPressed":{{"command":"OpenHelpArticle", "parameters":["release-main-menu-launch"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"6ac4d6535c14fd4c3bb4b1686c448b9f", "id":"launch-main-menu-launch", "isEnabled":true, "priority":940, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-10-13T02:27:36.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2022-04-21T22:15:01.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/8a34f833fe5e16e03afd2e86e1bc321e11222cc81ae091de7ddfd4f0e11e8f65.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://hello.vrchat.com/blog/avatar-dynamics-live"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"bb85463228e9a4d6b1eaaaee2a3a8ed3", "id":"avd-launch", "isEnabled":true, "priority":955, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-09-08T23:59:17.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2022-10-25T01:54:48.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/adaed2094c59dbe1f4fa6d92060ce8f6a2221e4ca5c140ecce292a44550f22e9.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://www.youtube.com/playlist?list=PLRUlfYGZBxVeEAVXkaG1uydyzFZAMOuMd"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"d58f7dc3a79db8562194a0c46eddcf31", "id":"launch-avatar-guide", "isEnabled":true, "priority":970, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-10-25T01:54:48.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2021-06-08T22:24:53.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/7ae34bb796e57b874979476641129116d431bde2709eb43a954ad076837df31a.png", "isNew":false, "onPressed":{{"command":"OpenSafetyMenu", "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"1723015530c9d0bc86cedde9ebd10206", "id":"LAUNCH---safety", "isEnabled":true, "priority":1000, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-09-08T23:59:19.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2021-09-13T22:50:42.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/a3c908711710358c37d6195a3e51f62b6b3c68f61e63d76529464ccdf23e618f.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://hello.vrchat.com/careers"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"0b26b848e9fb36bb73bff90ca114d482", "id":"launch-werehiring", "isEnabled":true, "priority":1010, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-09-08T23:59:19.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2021-06-08T22:24:53.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/8ffb45e3ae366bf86a67899d53660fc9d6541dd4a407a2ff6ba8b07a74fa86db.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://discord.com/invite/vrchat"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"e7b04c9401a09f38e1a22b904deb7092", "id":"launch-discord", "isEnabled":true, "priority":1020, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-09-08T23:59:17.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2021-11-08T23:45:26.0000000+01:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/c9bb5ca0943d090263c4f6bf7b25191a0330f34e6a0bf53cf742ea587a319048.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://feedback.vrchat.com/"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"40bb78d38b74c200c113db3a13b65663", "id":"launch-feedback", "isEnabled":true, "priority":1030, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-09-08T23:59:18.0000000+02:00"}}
2023.02.11 12:53:36 Log        -  {{"createdAt":"2021-06-08T22:24:53.0000000+02:00", "data":{{"imageUrl":"https://assets.vrchat.com/ips/assets/66de2dfb2320e6f50a2da082441b91ae4d07276b2e928528c04a00cc3b37bb0a.png", "isNew":false, "onPressed":{{"command":"OpenURL", "parameters":["https://docs.vrchat.com/docs/creating-your-first-avatar"], "id":"VRC.Core.ApiBindingCommandReference"}}, "id":"VRC.Core.ApiInfoPushSystem+Data"}}, "endDate":"0001-01-01T00:00:00.0000000", "hash":"694658dd6240d61f469ce6defe398290", "id":"LAUNCH---SDK", "isEnabled":true, "priority":1040, "releaseStatus":"public", "startDate":"0001-01-01T00:00:00.0000000", "tags":["quick-menu-banner", "user-all"], "updatedAt":"2022-09-08T23:59:19.0000000+02:00"}}
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Warning    -  [API] Attempted to authenticate non-API URI. No auth data was provided.
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuSelectedUserLocal
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuSelectedUserRemote
2023.02.11 12:53:36 Log        -  UIPage not in root page list: MenuInviteResponse
2023.02.11 12:53:36 Log        -  UIPage not in root page list: InviteMenuGallery
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuReportIssue
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuReportDetails
2023.02.11 12:53:36 Log        -  UIPage not in root page list: MenuGiftVRChatPlus
2023.02.11 12:53:36 Log        -  [AssetBundleDownloadManager] Unpacking Download
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuUserCamera
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuChangeAudioInputDevice
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuAvatarDetails
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuAvatarDetails
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuAddMessage
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuAddWorldToPlaylist
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuAddAvatarToFavorites
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QM_Notification_MoreActions
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuViewPhoto
2023.02.11 12:53:36 Log        -  UIPage not in root page list: ConfirmListDialog
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuShowAvatarInteractionInfo
2023.02.11 12:53:36 Log        -  UIPage not in root page list: QuickMenuHoveredUser
2023.02.11 12:53:36 Log        -  UIPage not in root page list: ConfirmDialog

Now it gets interesting:

2023.02.11 12:53:36 Log        -  [Behaviour] Lifting black fade
2023.02.11 12:53:36 Log        -  [Behaviour] I am MASTER
Actor Nr: 1

Remember, how the clients said it’s not the master at first? That seems to be no longer the case. The second client seems to have removed the first client in their view, as suspected.

2023.02.11 12:53:37 Log        -  [Behaviour] Finished entering world.
2023.02.11 12:53:37 Log        -  [Behaviour] OnPlayerJoined EinDev
2023.02.11 12:53:37 Log        -  [Behaviour] OnPlayerJoinComplete EinDev
2023.02.11 12:53:37 Log        -  [Behaviour] Initialized PlayerAPI "EinDev" is local
(...)
2023.02.11 12:53:40 Log        -  [Network Processing] Sending transfer of ownership of 100001 to 1.
2023.02.11 12:53:40 Log        -  [Network Processing] Sending transfer of ownership of 100002 to 1.
2023.02.11 12:53:40 Log        -  [Network Processing] Sending transfer of ownership of 100003 to 1.
2023.02.11 12:53:40 Log        -  [Network Processing] Sending transfer of ownership of 200001 to 2.
2023.02.11 12:53:40 Log        -  [Network Processing] Sending transfer of ownership of 200002 to 2.
2023.02.11 12:53:40 Log        -  [Network Processing] Sending transfer of ownership of 200003 to 2.
2023.02.11 12:53:40 Log        -  [Behaviour] Initialized PlayerAPI "EinDev" is remote
2023.02.11 12:53:40 Log        -  [Behaviour] CacheComponents: ParticleSystems 0, AudioSources 0
2023.02.11 12:53:40 Log        -  [Network Processing] Received ownership transfer of 100001 from EinDev to 1
2023.02.11 12:53:40 Log        -  [Network Processing] Received ownership transfer of 100002 from EinDev to 1
2023.02.11 12:53:40 Log        -  [Network Processing] Received ownership transfer of 100003 from EinDev to 1

Huh? That’s a bit unusual i think.

Note: API responses with “Response was missing username.” are still slowly coming in, because this is all happening very fast.

2023.02.11 12:53:40 Log        -  [Behaviour] Received executive message: Unusual client behaviour

I agreee on that.

No idea what that is about
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:1723023408
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:6017990704
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:10312958000
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:14607925296
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:18902892592
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:23197859888
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:27492827184
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:31787794480
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:36082761776
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:40377729072
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:44672696368
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:48967663664
2023.02.11 12:53:42 Error      -  Buffer already contains chain of id:53262630960
2023.02.11 12:53:47 Log        -  [Behaviour] OnLeftRoom
2023.02.11 12:53:47 Log        -  [Behaviour] OnDisconnected: DisconnectByServerLogic
2023.02.11 12:53:47 Error      -  [Behaviour] {{ NameServerAddress: 'ns.photonengine.io:5058', MasterServerAddress: '91.199.81.55:5055', GameServerAddress: '216.120.180.98:5056', Code: 'DisconnectByServerLogic', State: 'Disconnected', Time: '959576.84' }}
2023.02.11 12:53:47 Log        -  [Behaviour] Unregistering EinDev
2023.02.11 12:53:47 Log        -  [Behaviour] OnPlayerLeft EinDev
2023.02.11 12:53:47 Log        -  [Behaviour] Removed player EinDev
2023.02.11 12:53:47 Log        -  [Behaviour] Unregistering EinDev
2023.02.11 12:53:47 Log        -  [Behaviour] OnPlayerLeft EinDev
2023.02.11 12:53:47 Log        -  [Behaviour] Removed player EinDev
2023.02.11 12:53:47 Log        -  [Behaviour] No longer in room, cleaning up.
2023.02.11 12:53:47 Log        -  [Behaviour] Destination was not set, going to default world.

I think anything after that is irrelevant, because (at latest here) whatever is causing this already did it’s job.

(note: i accidentally pressed enter, more coming soon TM)

As i cannot seem to edit the post anymore: Against my first assumption, i am no longer sure which one of these clients is the master and which not.

So i just solved this myself. Turns out i had my SDK point to an old version which was not updated anymore. (Steam only seems to maintain one location of a game if you have the game downloaded in multiple storage locations)
Setting the SDK to “Revert to Default” fixed it.

To prevent this in the future, a “Hey, you are using an old VRC version. Are you sure?” would maybe have helped, but this is primarily my pure incompetence that caused it.

2 Likes

You are a G my good sir, fixed it for me too