Back to build
Raw log | Switch to full mode | Login

Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-05-24 08:09:49,422 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/k/recipe_cleanup/01FpYdl' 2019-05-24 08:09:49,422 - root: [WARNING] task_output_dir '/b/s/w/ir/k/recipe_cleanup/01FpYdl' already exists! 2019-05-24 08:09:49,422 - root: [WARNING] task_output_dir existing content: [] 2019-05-24 08:09:49,422 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpM3BYcx.json -output-dir /b/s/w/ir/k/recipe_cleanup/01FpYdl -task-summary-json /b/s/w/ir/tmp/t/tmpkVvq3u.json [D2019-05-24T08:09:49.439764-07:00 89516 0 auth.go:1265] Minting a new token {"key":"luci_ctx/8e3d0e19e1b74201ce86230030dddc31d73089501d0e295cb0fe5971984174cf", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-24T08:09:49.439864-07:00 89516 0 luci_ctx.go:138] POST http://127.0.0.1:65413/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/8e3d0e19e1b74201ce86230030dddc31d73089501d0e295cb0fe5971984174cf", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-24T08:09:49.441614-07:00 89516 0 auth.go:1222] Token expires in 6m52.558403s {"key":"luci_ctx/8e3d0e19e1b74201ce86230030dddc31d73089501d0e295cb0fe5971984174cf", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-24T08:09:51.032298-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:09:52.537320-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:09:54.044181-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:09:55.574844-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:09:57.279190-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:09:59.507663-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:01.284876-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:03.087443-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:05.066117-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:06.935266-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:09.285742-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:11.387721-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:14.430386-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:16.673119-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:19.119769-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:21.816322-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:24.618219-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:27.257859-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:30.144112-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:35.044496-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:38.033698-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:41.099369-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:44.226017-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 [D2019-05-24T08:10:47.460545-07:00 89516 0 collect.go:311] Waiting task_id: 4503353fbfd24210 4503353fbfd24210: exit 0 [I 2019-05-24 08:09:34] About to install Xcode 10l232m in Xcode.app for ios [08:09:35:INFO] Using Xcode version 10.0 build 10L232m at /b/s/w/ir/Xcode.app/Contents/Developer [08:09:35:INFO] Killing simulators. Device wiped. [08:09:44:DEBUG] Setting sigterm handler. [08:09:45:INFO] User defaults from command line: [08:09:45:INFO] IDETestRunSpecificationPath = /var/folders/2j/22s2gz0s7hn48k32d47clxf80000gm/T/61590EFE-E058-4B20-A082-E6FD02DBE58C [08:09:45:INFO] [08:09:45:INFO] Testing started on 'iPhone 6s' [08:09:45:INFO] 2019-05-24 08:09:45.863 xcodebuild[3672:38232] IDETestOperationsObserverDebug: Writing diagnostic log for test session to: [08:09:45:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-bmrscbzryptugidvhrovakvldyve/Logs/Test/Test-Transient Testing-2019.05.24_08-09-45--0700.xcresult/1_Test/Diagnostics/apprtcmobile_tests_module-50862A22-B469-4215-9BC4-2CFE1FD1E409/apprtcmobile_tests_module-D8FA2468-1A5E-40D9-AF14-82274C4B51AE/Session-apprtcmobile_tests_module-2019-05-24_080945-Eyyzzc.log [08:09:45:INFO] 2019-05-24 08:09:45.864 xcodebuild[3672:38205] [MT] IDETestOperationsObserverDebug: (0A36E2D4-F395-4C16-8CE9-0D9B9BD9D5E9) Beginning test session apprtcmobile_tests_module-0A36E2D4-F395-4C16-8CE9-0D9B9BD9D5E9 at 2019-05-24 08:09:45.864 with Xcode 10L232m on target <DVTiPhoneSimulator: 0x7f98bff95110> { [08:09:45:INFO] SimDevice: iPhone 6s (74FAF2A5-F52D-4981-810C-48517EA8B848, iOS 12.0, Shutdown) [08:09:45:INFO] } (12.0 (16A5354b)) [08:10:01:INFO] objc[3785]: Class RTCDispatcher is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114977100) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e4492f0). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCCameraPreviewView is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114977128) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e449318). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCEncodedImage is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114975fd0) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e449390). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCRtpFragmentationHeader is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114976020) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e4493e0). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCVideoCapturer is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114976048) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e449408). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCVideoCodecInfo is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114976098) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e449458). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCVideoEncoderQpThresholds is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x1149760e8) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e4494a8). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCVideoEncoderSettings is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114976160) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e449520). One of the two will be used. Which one is undefined. [08:10:01:INFO] objc[3785]: Class RTCVideoFrame is implemented in both /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/Frameworks/WebRTC.framework/WebRTC (0x114976188) and /Users/chrome-bot/Library/Developer/CoreSimulator/Devices/74FAF2A5-F52D-4981-810C-48517EA8B848/data/Containers/Bundle/Application/93D31826-95FB-4AFB-95AE-25C4651F45FD/apprtcmobile_tests.app/apprtcmobile_tests (0x10e449548). One of the two will be used. Which one is undefined. [08:10:01:INFO] 2019-05-24 08:10:01.653 apprtcmobile_tests[3785:39270] +[CATransaction synchronize] called within transaction [08:10:03:INFO] Test Suite 'All tests' started at 2019-05-24 08:10:03.834 [08:10:03:INFO] Test Suite 'apprtcmobile_tests.app' started at 2019-05-24 08:10:03.835 [08:10:03:INFO] Test Suite 'ARDAppClientTest' started at 2019-05-24 08:10:03.835 [08:10:03:INFO] Test Case '-[ARDAppClientTest testSession]' started. [08:10:03:INFO] [000:000] [9987] (audio_device_module.mm:21): CreateAudioDeviceModule [08:10:03:INFO] [000:001] [9987] (audio_device_module_ios.mm:44): current platform is IOS [08:10:03:INFO] [000:001] [9987] (audio_device_module_ios.mm:45): iPhone Audio APIs will be utilized. [08:10:03:INFO] [000:012] [9987] (audio_processing_impl.cc:435): Capture analyzer activated: 0 [08:10:03:INFO] Capture post processor activated: 0 [08:10:03:INFO] Render pre processor activated: 0 [08:10:03:INFO] [000:017] [9987] (webrtc_voice_engine.cc:196): WebRtcVoiceEngine::WebRtcVoiceEngine [08:10:03:INFO] [000:017] [9987] (webrtc_video_engine.cc:479): WebRtcVideoEngine::WebRtcVideoEngine() [08:10:03:INFO] [000:022] [27919] (webrtc_voice_engine.cc:219): WebRtcVoiceEngine::Init [08:10:03:INFO] [000:022] [27919] (webrtc_voice_engine.cc:227): Supported send codecs in order of preference: [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): opus/48000/2 { minptime=10 useinbandfec=1 } (111) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): ISAC/16000/1 (103) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): ISAC/32000/1 (104) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): G722/8000/1 (9) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): ILBC/8000/1 (102) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): PCMU/8000/1 (0) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): PCMA/8000/1 (8) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): CN/32000/1 (106) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): CN/16000/1 (105) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): CN/8000/1 (13) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): telephone-event/48000/1 (110) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): telephone-event/32000/1 (112) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): telephone-event/16000/1 (113) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:230): telephone-event/8000/1 (126) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:233): Supported recv codecs in order of preference: [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): opus/48000/2 { minptime=10 useinbandfec=1 } (111) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): ISAC/16000/1 (103) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): ISAC/32000/1 (104) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): G722/8000/1 (9) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): ILBC/8000/1 (102) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): PCMU/8000/1 (0) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): PCMA/8000/1 (8) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): CN/32000/1 (106) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): CN/16000/1 (105) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): CN/8000/1 (13) [08:10:03:INFO] [000:029] [27919] (webrtc_voice_engine.cc:236): telephone-event/48000/1 (110) [08:10:03:INFO] [000:030] [27919] (webrtc_voice_engine.cc:236): telephone-event/32000/1 (112) [08:10:03:INFO] [000:030] [27919] (webrtc_voice_engine.cc:236): telephone-event/16000/1 (113) [08:10:03:INFO] [000:030] [27919] (webrtc_voice_engine.cc:236): telephone-event/8000/1 (126) [08:10:03:INFO] [000:030] [27919] (audio_device_module_ios.mm:69): Init [08:10:03:INFO] [000:030] [27919] (audio_device_buffer.cc:63): AudioDeviceBuffer::ctor [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:117): AudioDeviceIOS::ctor<NSThread: 0x6000017bbc40>{number = 3, name = (null)} [08:10:03:INFO] [000:031] [27919] (audio_device_module_ios.mm:49): AttachAudioBuffer [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:133): AudioDeviceIOS::AttachAudioBuffer [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:140): AudioDeviceIOS::Init [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:85): LogDeviceInfo [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:87): system name: iOS [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:88): system version: 12.0 [08:10:03:INFO] [000:031] [27919] (audio_device_ios.mm:89): device type: iPhone [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:90): device name: x86_64 [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:91): process name: apprtcmobile_tests [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:92): process ID: 3785 [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:93): OS version: Version 12.0 (Build 16A5354b) [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:94): processing cores: 4 [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:95): low power mode: 0 [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:97): TARGET_IPHONE_SIMULATOR is defined [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:99): DeviceIsSimulator: 1 [08:10:03:INFO] [000:032] [27919] (audio_device_ios.mm:663): AudioDeviceIOS::UpdateAudioDevicebuffer [08:10:03:INFO] [000:033] [27919] (audio_device_buffer.cc:186): SetPlayoutSampleRate(48000) [08:10:03:INFO] [000:033] [27919] (audio_device_buffer.cc:206): SetPlayoutChannels(1) [08:10:03:INFO] [000:033] [27919] (audio_device_buffer.cc:180): SetRecordingSampleRate(48000) [08:10:03:INFO] [000:033] [27919] (audio_device_buffer.cc:200): SetRecordingChannels(1) [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:406): SetPlayoutDevice(0) [08:10:03:INFO] [000:033] [27919] (audio_device_ios.mm:1007): virtual int32_t webrtc::ios_adm::AudioDeviceIOS::SetPlayoutDevice(uint16_t): Not implemented [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:108): InitSpeaker [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:310): StereoPlayoutIsAvailable [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:317): output: 0 [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:322): SetStereoPlayout(0) [08:10:03:INFO] [000:033] [27919] (audio_device_ios.mm:1060): virtual int32_t webrtc::ios_adm::AudioDeviceIOS::SetStereoPlayout(bool): Not implemented [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:330): stereo playout is not supported [08:10:03:INFO] [000:033] [27919] (adm_helpers.cc:57): Failed to set stereo playout mode. [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:468): SetRecordingDevice(0) [08:10:03:INFO] [000:033] [27919] (audio_device_ios.mm:1109): virtual int32_t webrtc::ios_adm::AudioDeviceIOS::SetRecordingDevice(uint16_t): Not implemented [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:114): InitMicrophone [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:277): StereoRecordingIsAvailable [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:284): output: 0 [08:10:03:INFO] [000:033] [27919] (audio_device_module_ios.mm:289): SetStereoRecording(0) [08:10:03:INFO] [000:033] [27919] (adm_helpers.cc:77): Failed to set stereo recording mode. [08:10:03:INFO] [000:033] [27919] (apm_helpers.cc:32): Setting AGC mode to 2 [08:10:03:INFO] [000:033] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 0 [08:10:03:INFO] [000:034] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:03:INFO] [000:034] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:03:INFO] [000:035] [27919] (audio_device_module_ios.mm:581): RegisterAudioCallback [08:10:03:INFO] [000:035] [27919] (audio_device_buffer.cc:81): RegisterAudioCallback [08:10:03:INFO] [000:035] [27919] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {aec: 1, agc: 1, ns: 1, hf: 1, swap: 0, audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, typing: 1, experimental_agc: 0, extended_filter_aec: 0, delay_agnostic_aec: 0, experimental_ns: 0, residual_echo_detector: 1, } [08:10:03:INFO] [000:035] [27919] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:03:INFO] [000:035] [27919] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:03:INFO] [000:035] [27919] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:03:INFO] [000:036] [27919] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:03:INFO] [000:036] [27919] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:03:INFO] [000:036] [27919] (audio_device_module_ios.mm:600): output: 0 [08:10:03:INFO] [000:036] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 0 [08:10:03:INFO] [000:037] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:03:INFO] [000:037] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:03:INFO] [000:037] [27919] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:03:INFO] [000:037] [27919] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:03:INFO] [000:037] [27919] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:03:INFO] [000:037] [27919] (audio_device_module_ios.mm:616): output: 0 [08:10:03:INFO] [000:037] [27919] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:03:INFO] [000:037] [27919] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:03:INFO] [000:037] [27919] (audio_device_module_ios.mm:632): output: 0 [08:10:03:INFO] [000:037] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 0 [08:10:03:INFO] [000:037] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:03:INFO] [000:037] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:03:INFO] [000:037] [27919] (apm_helpers.cc:62): NS set to 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:447): Stereo swapping enabled? 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:03:INFO] [000:038] [27919] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:03:INFO] [000:038] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:03:INFO] [000:038] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:03:INFO] [000:038] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:03:INFO] (RTCLogging.mm:33): (ARDAppClient.m:270 -[ARDAppClient connectToRoomWithId:settings:isLoopback:]_block_invoke): Joined room:testRoom on room server. [08:10:03:INFO] [000:046] [9987] (openssl_identity.cc:44): Making key pair [08:10:04:INFO] [000:279] [9987] (openssl_identity.cc:92): Returning key pair [08:10:04:INFO] [000:281] [9987] (openssl_certificate.cc:58): Making certificate for WebRTC [08:10:04:INFO] [000:294] [9987] (openssl_certificate.cc:108): Returning certificate [08:10:04:INFO] [000:305] [9987] (RTCCertificate.mm:63): CERT PEM [08:10:04:INFO] [000:305] [9987] (RTCCertificate.mm:64): -----BEGIN CERTIFICATE----- [08:10:04:INFO] MIIBnjCCAQegAwIBAgIJAIhCv08mCVHtMA0GCSqGSIb3DQEBCwUAMBExDzANBgNV [08:10:04:INFO] BAMMBldlYlJUQzAeFw0xOTA1MjMxNTEwMDNaFw0xOTA1MjQxNTExNDNaMBExDzAN [08:10:04:INFO] BgNVBAMMBldlYlJUQzCBnzANBgkqhkiG9w0BAQEFAAOBjQAwgYkCgYEAyfxjC4h3 [08:10:04:INFO] H5p6mBRPxagYuyz0K3LYrAEuwNlUDIF/WRUo9vIFuSy23Pzu10eTqpVFzpqotfMw [08:10:04:INFO] dwdKXUxp6cv5v5MG2vLhb2/nikqIBktDrSqfLxqKGDdHbGJG3UXyWAP79Rf+r6tq [08:10:04:INFO] LrkN/DaOS81w/3U2ONHoCc8r5K08Hifw1OMCAwEAATANBgkqhkiG9w0BAQsFAAOB [08:10:04:INFO] gQA2ATE/utrmDqzzc/SKInXBBrJebqqRKCNh34zdUmM+KNlZKhk0ImKxh0r2vPEu [08:10:04:INFO] aj8ZWYPGd+p/hegowOcT3bFfEs5GfPQsoCk+knG8QrBVvl7m8bptWEAK6vQbuzy8 [08:10:04:INFO] Vo0Enk8DmeBPnAekoJzQIXMCspwU1zacNpsfRMqN5bVnEQ== [08:10:04:INFO] -----END CERTIFICATE----- [08:10:04:INFO] [08:10:04:INFO] [000:306] [9987] (RTCConfiguration.mm:213): Have configured cert - using it. [08:10:04:INFO] [000:310] [9987] (RTCConfiguration.mm:218): Created cert from PEM strings. [08:10:04:INFO] [000:314] [27919] (rtc_event_log_impl.cc:63): Creating legacy encoder for RTC event log. [08:10:04:INFO] [000:315] [27919] (peer_connection_factory.cc:361): Using default network controller factory [08:10:04:INFO] [000:318] [27919] (bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive [08:10:04:INFO] [000:328] [27919] (paced_sender.cc:421): ProcessThreadAttached 0xd1f0ac20 [08:10:04:INFO] [000:328] [27919] (cpu_info.cc:53): Available number of cores: 4 [08:10:04:INFO] [000:331] [27919] (aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85 [08:10:04:INFO] [000:331] [27919] (remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating. [08:10:04:INFO] [000:332] [27919] (remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250 [08:10:04:INFO] [000:343] [50187] (webrtc_session_description_factory.cc:314): Using certificate supplied to the constructor. [08:10:04:INFO] [000:346] [50187] (peer_connection.cc:1386): Adding audio transceiver in response to a call to AddTrack. [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:396 -[ARDAppClient peerConnectionShouldNegotiate:]): WARNING: Renegotiation needed but unimplemented. [08:10:04:INFO] [000:349] [9987] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f138d0): created DTMF sender: RTCDtmfSender { [08:10:04:INFO] remainingTones: [08:10:04:INFO] duration: 0.100000 sec [08:10:04:INFO] interToneGap: 0.050000 sec [08:10:04:INFO] } [08:10:04:INFO] [000:350] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d25160): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] [000:350] [9987] (RTCLogging.mm:33): (RTCFileVideoCapturer.m:103 -[RTCFileVideoCapturer stopCapture]): File capturer stopped. [08:10:04:INFO] [000:351] [50187] (peer_connection.cc:1386): Adding video transceiver in response to a call to AddTrack. [08:10:04:INFO] [000:351] [50187] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:04:INFO] [000:351] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d192c0): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f138c0): created DTMF sender: RTCDtmfSender { [08:10:04:INFO] remainingTones: [08:10:04:INFO] duration: 0.100000 sec [08:10:04:INFO] interToneGap: 0.050000 sec [08:10:04:INFO] } [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d25100): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x600000301b90): created receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: cb180711-83a1-4e85-8e6a-33c93fda135b [08:10:04:INFO] } [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x600000301b00): created transceiver: RTCRtpTransceiver { [08:10:04:INFO] sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: cb180711-83a1-4e85-8e6a-33c93fda135b [08:10:04:INFO] } [08:10:04:INFO] } [08:10:04:INFO] [000:353] [50187] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d251c0): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x600000301c20): created receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 9598fc53-5256-4229-8c50-0b9cd2e4ebe6 [08:10:04:INFO] } [08:10:04:INFO] [000:353] [9987] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x600000301bc0): created transceiver: RTCRtpTransceiver { [08:10:04:INFO] sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 9598fc53-5256-4229-8c50-0b9cd2e4ebe6 [08:10:04:INFO] } [08:10:04:INFO] } [08:10:04:INFO] [000:357] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 102 to 127 [08:10:04:INFO] [000:357] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 104 to 125 [08:10:04:INFO] [000:357] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 106 to 124 [08:10:04:INFO] [000:357] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 103 to 123 [08:10:04:INFO] [000:357] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 105 to 122 [08:10:04:INFO] [000:358] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 1 to 14 [08:10:04:INFO] [000:358] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 2 to 13 [08:10:04:INFO] [000:358] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 3 to 12 [08:10:04:INFO] [000:358] [50187] (media_session.cc:316): Duplicate id found. Reassigning from 5 to 11 [08:10:04:INFO] [000:364] [27919] (rtc_event_log_impl.cc:115): Starting WebRTC event log. (Timestamp, UTC) = (2551155185, 1558710604209447). [08:10:04:INFO] [000:365] [9987] (audio_device_module.mm:21): CreateAudioDeviceModule [08:10:04:INFO] [000:365] [9987] (audio_device_module_ios.mm:44): current platform is IOS [08:10:04:INFO] [000:365] [9987] (audio_device_module_ios.mm:45): iPhone Audio APIs will be utilized. [08:10:04:INFO] [000:365] [9987] (audio_processing_impl.cc:435): Capture analyzer activated: 0 [08:10:04:INFO] Capture post processor activated: 0 [08:10:04:INFO] Render pre processor activated: 0 [08:10:04:INFO] [000:366] [9987] (webrtc_voice_engine.cc:196): WebRtcVoiceEngine::WebRtcVoiceEngine [08:10:04:INFO] [000:366] [9987] (webrtc_video_engine.cc:479): WebRtcVideoEngine::WebRtcVideoEngine() [08:10:04:INFO] [000:366] [50691] (webrtc_voice_engine.cc:219): WebRtcVoiceEngine::Init [08:10:04:INFO] [000:366] [50691] (webrtc_voice_engine.cc:227): Supported send codecs in order of preference: [08:10:04:INFO] [000:366] [50691] (webrtc_voice_engine.cc:230): opus/48000/2 { minptime=10 useinbandfec=1 } (111) [08:10:04:INFO] [000:366] [50691] (webrtc_voice_engine.cc:230): ISAC/16000/1 (103) [08:10:04:INFO] [000:366] [50691] (webrtc_voice_engine.cc:230): ISAC/32000/1 (104) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): G722/8000/1 (9) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): ILBC/8000/1 (102) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): PCMU/8000/1 (0) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): PCMA/8000/1 (8) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): CN/32000/1 (106) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): CN/16000/1 (105) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): CN/8000/1 (13) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): telephone-event/48000/1 (110) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): telephone-event/32000/1 (112) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): telephone-event/16000/1 (113) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:230): telephone-event/8000/1 (126) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:233): Supported recv codecs in order of preference: [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): opus/48000/2 { minptime=10 useinbandfec=1 } (111) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): ISAC/16000/1 (103) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): ISAC/32000/1 (104) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): G722/8000/1 (9) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): ILBC/8000/1 (102) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): PCMU/8000/1 (0) [08:10:04:INFO] [000:367] [50691] (webrtc_voice_engine.cc:236): PCMA/8000/1 (8) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): CN/32000/1 (106) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): CN/16000/1 (105) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): CN/8000/1 (13) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): telephone-event/48000/1 (110) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): telephone-event/32000/1 (112) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): telephone-event/16000/1 (113) [08:10:04:INFO] [000:369] [50691] (webrtc_voice_engine.cc:236): telephone-event/8000/1 (126) [08:10:04:INFO] [000:369] [50691] (audio_device_module_ios.mm:69): Init [08:10:04:INFO] [000:369] [50691] (audio_device_buffer.cc:63): AudioDeviceBuffer::ctor [08:10:04:INFO] [000:369] [50691] (audio_device_ios.mm:117): AudioDeviceIOS::ctor<NSThread: 0x6000017b1340>{number = 4, name = (null)} [08:10:04:INFO] [000:369] [50691] (audio_device_module_ios.mm:49): AttachAudioBuffer [08:10:04:INFO] [000:369] [50691] (audio_device_ios.mm:133): AudioDeviceIOS::AttachAudioBuffer [08:10:04:INFO] [000:369] [50691] (audio_device_ios.mm:140): AudioDeviceIOS::Init [08:10:04:INFO] [000:370] [50691] (audio_device_ios.mm:85): LogDeviceInfo [08:10:04:INFO] [000:370] [50691] (audio_device_ios.mm:87): system name: iOS [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:88): system version: 12.0 [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:89): device type: iPhone [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:90): device name: x86_64 [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:91): process name: apprtcmobile_tests [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:92): process ID: 3785 [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:93): OS version: Version 12.0 (Build 16A5354b) [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:94): processing cores: 4 [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:95): low power mode: 0 [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:97): TARGET_IPHONE_SIMULATOR is defined [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:99): DeviceIsSimulator: 1 [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:663): AudioDeviceIOS::UpdateAudioDevicebuffer [08:10:04:INFO] [000:371] [50691] (audio_device_buffer.cc:186): SetPlayoutSampleRate(48000) [08:10:04:INFO] [000:371] [50691] (audio_device_buffer.cc:206): SetPlayoutChannels(1) [08:10:04:INFO] [000:371] [50691] (audio_device_buffer.cc:180): SetRecordingSampleRate(48000) [08:10:04:INFO] [000:371] [50691] (audio_device_buffer.cc:200): SetRecordingChannels(1) [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:406): SetPlayoutDevice(0) [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:1007): virtual int32_t webrtc::ios_adm::AudioDeviceIOS::SetPlayoutDevice(uint16_t): Not implemented [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:108): InitSpeaker [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:310): StereoPlayoutIsAvailable [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:317): output: 0 [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:322): SetStereoPlayout(0) [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:1060): virtual int32_t webrtc::ios_adm::AudioDeviceIOS::SetStereoPlayout(bool): Not implemented [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:330): stereo playout is not supported [08:10:04:INFO] [000:371] [50691] (adm_helpers.cc:57): Failed to set stereo playout mode. [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:468): SetRecordingDevice(0) [08:10:04:INFO] [000:371] [50691] (audio_device_ios.mm:1109): virtual int32_t webrtc::ios_adm::AudioDeviceIOS::SetRecordingDevice(uint16_t): Not implemented [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:114): InitMicrophone [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:277): StereoRecordingIsAvailable [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:284): output: 0 [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:289): SetStereoRecording(0) [08:10:04:INFO] [000:371] [50691] (adm_helpers.cc:77): Failed to set stereo recording mode. [08:10:04:INFO] [000:371] [50691] (apm_helpers.cc:32): Setting AGC mode to 2 [08:10:04:INFO] [000:371] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 0 [08:10:04:INFO] [000:371] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:371] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:371] [50691] (audio_device_module_ios.mm:581): RegisterAudioCallback [08:10:04:INFO] [000:371] [50691] (audio_device_buffer.cc:81): RegisterAudioCallback [08:10:04:INFO] [000:372] [50691] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {aec: 1, agc: 1, ns: 1, hf: 1, swap: 0, audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, typing: 1, experimental_agc: 0, extended_filter_aec: 0, delay_agnostic_aec: 0, experimental_ns: 0, residual_echo_detector: 1, } [08:10:04:INFO] [000:373] [50691] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:04:INFO] [000:373] [50691] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:04:INFO] [000:373] [50691] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:04:INFO] [000:373] [50691] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:04:INFO] [000:373] [50691] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:373] [50691] (audio_device_module_ios.mm:600): output: 0 [08:10:04:INFO] [000:373] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 0 [08:10:04:INFO] [000:373] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:373] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:373] [50691] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:04:INFO] [000:373] [50691] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:04:INFO] [000:373] [50691] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:373] [50691] (audio_device_module_ios.mm:616): output: 0 [08:10:04:INFO] [000:373] [50691] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:04:INFO] [000:373] [50691] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:374] [50691] (audio_device_module_ios.mm:632): output: 0 [08:10:04:INFO] [000:374] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 0 [08:10:04:INFO] [000:374] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:374] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:374] [50691] (apm_helpers.cc:62): NS set to 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:447): Stereo swapping enabled? 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:04:INFO] [000:374] [50691] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:04:INFO] [000:375] [50691] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:04:INFO] [000:375] [50691] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:04:INFO] [000:375] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:375] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:375] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:270 -[ARDAppClient connectToRoomWithId:settings:isLoopback:]_block_invoke): Joined room:testRoom on room server. [08:10:04:INFO] [000:375] [9987] (openssl_identity.cc:44): Making key pair [08:10:04:INFO] [000:536] [9987] (openssl_identity.cc:92): Returning key pair [08:10:04:INFO] [000:536] [9987] (openssl_certificate.cc:58): Making certificate for WebRTC [08:10:04:INFO] [000:540] [9987] (openssl_certificate.cc:108): Returning certificate [08:10:04:INFO] [000:540] [9987] (RTCCertificate.mm:63): CERT PEM [08:10:04:INFO] [000:540] [9987] (RTCCertificate.mm:64): -----BEGIN CERTIFICATE----- [08:10:04:INFO] MIIBnjCCAQegAwIBAgIJAIxgFTX7LofaMA0GCSqGSIb3DQEBCwUAMBExDzANBgNV [08:10:04:INFO] BAMMBldlYlJUQzAeFw0xOTA1MjMxNTEwMDRaFw0xOTA1MjQxNTExNDRaMBExDzAN [08:10:04:INFO] BgNVBAMMBldlYlJUQzCBnzANBgkqhkiG9w0BAQEFAAOBjQAwgYkCgYEAuMSmgQ8O [08:10:04:INFO] pDQnzBOtLtDB85j55JCL9PnvwcQs3V/3cF7err5cU2H3FjE5kyjVyxSRNsOtI+47 [08:10:04:INFO] vN2S0IVrIlocPY/ZrG8gWMzYqTQsLWxNTRWeIeqUc+CTQaSmzbAfCIPT6OjYeYzx [08:10:04:INFO] JZNDkKnB4ESWSveyVEYf3UrzOd1DQLEY6acCAwEAATANBgkqhkiG9w0BAQsFAAOB [08:10:04:INFO] gQCndnYQiL8XRUfh2IA88ogpYOfMXsLoGm4jG30tE3/JGW2rkD6KtlWZmqyvHtBJ [08:10:04:INFO] ugLkQPVU2QpezqjYtGwR+tSRDlvneUkOlcVvBDjMgtgSimhTpm9QlpjE/fNf1cKk [08:10:04:INFO] V550FSkhMGcQ5Dmj9fn7RgEcG+6RvLlcCyH3wp6IzTXaBA== [08:10:04:INFO] -----END CERTIFICATE----- [08:10:04:INFO] [08:10:04:INFO] [000:540] [9987] (RTCConfiguration.mm:213): Have configured cert - using it. [08:10:04:INFO] [000:542] [9987] (RTCConfiguration.mm:218): Created cert from PEM strings. [08:10:04:INFO] [000:542] [50691] (rtc_event_log_impl.cc:63): Creating legacy encoder for RTC event log. [08:10:04:INFO] [000:542] [50691] (peer_connection_factory.cc:361): Using default network controller factory [08:10:04:INFO] [000:542] [50691] (bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive [08:10:04:INFO] [000:542] [50691] (paced_sender.cc:421): ProcessThreadAttached 0xd1e29360 [08:10:04:INFO] [000:543] [50691] (aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85 [08:10:04:INFO] [000:543] [50691] (remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating. [08:10:04:INFO] [000:543] [50691] (remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250 [08:10:04:INFO] [000:544] [50947] (webrtc_session_description_factory.cc:314): Using certificate supplied to the constructor. [08:10:04:INFO] [000:544] [50947] (peer_connection.cc:1386): Adding audio transceiver in response to a call to AddTrack. [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:396 -[ARDAppClient peerConnectionShouldNegotiate:]): WARNING: Renegotiation needed but unimplemented. [08:10:04:INFO] [000:545] [9987] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f13d70): created DTMF sender: RTCDtmfSender { [08:10:04:INFO] remainingTones: [08:10:04:INFO] duration: 0.100000 sec [08:10:04:INFO] interToneGap: 0.050000 sec [08:10:04:INFO] } [08:10:04:INFO] [000:545] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d26bc0): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] [000:545] [9987] (RTCLogging.mm:33): (RTCFileVideoCapturer.m:103 -[RTCFileVideoCapturer stopCapture]): File capturer stopped. [08:10:04:INFO] [000:545] [50947] (peer_connection.cc:1386): Adding video transceiver in response to a call to AddTrack. [08:10:04:INFO] [000:545] [50947] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:04:INFO] [000:548] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d271a0): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] [000:549] [9987] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f13c70): created DTMF sender: RTCDtmfSender { [08:10:04:INFO] remainingTones: [08:10:04:INFO] duration: 0.100000 sec [08:10:04:INFO] interToneGap: 0.050000 sec [08:10:04:INFO] } [08:10:04:INFO] [000:549] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d272c0): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] [000:549] [9987] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x6000003030f0): created receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 7908a7ac-87c3-49a4-9da5-94e32eec92d4 [08:10:04:INFO] } [08:10:04:INFO] [000:549] [9987] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x600000303090): created transceiver: RTCRtpTransceiver { [08:10:04:INFO] sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 7908a7ac-87c3-49a4-9da5-94e32eec92d4 [08:10:04:INFO] } [08:10:04:INFO] } [08:10:04:INFO] [000:551] [50947] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:04:INFO] [000:551] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d20000): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] [000:551] [9987] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x600000306220): created receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 6e6ae9cd-17fd-458f-8eb6-ace431a0f684 [08:10:04:INFO] } [08:10:04:INFO] [000:551] [9987] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x600000306280): created transceiver: RTCRtpTransceiver { [08:10:04:INFO] sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 6e6ae9cd-17fd-458f-8eb6-ace431a0f684 [08:10:04:INFO] } [08:10:04:INFO] } [08:10:04:INFO] [000:553] [50691] (rtc_event_log_impl.cc:115): Starting WebRTC event log. (Timestamp, UTC) = (2551344164, 1558710604398430). [08:10:04:INFO] [000:554] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:04:INFO] [000:556] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:502): Set backup connection ping interval to 25000 milliseconds. [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:511): Set ICE receiving timeout to 2500 milliseconds [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:518): Set ping most likely connection to 0 [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:525): Set stable_writable_connection_ping_interval to 2500 [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:538): Set presume writable when fully relayed to 0 [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:547): Set regather_on_failed_networks_interval to 300000 [08:10:04:INFO] [000:562] [30487] (p2p_transport_channel.cc:566): Set receiving_switching_delay to 1000 [08:10:04:INFO] [000:562] [30487] (jsep_transport_controller.cc:1213): Creating DtlsSrtpTransport. [08:10:04:INFO] [000:563] [30487] (dtls_srtp_transport.cc:61): Setting RTCP Transport on 0 transport 0 [08:10:04:INFO] [000:563] [30487] (dtls_srtp_transport.cc:66): Setting RTP Transport on 0 transport d1e2c100 [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:502): Set backup connection ping interval to 25000 milliseconds. [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:511): Set ICE receiving timeout to 2500 milliseconds [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:518): Set ping most likely connection to 0 [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:525): Set stable_writable_connection_ping_interval to 2500 [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:538): Set presume writable when fully relayed to 0 [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:547): Set regather_on_failed_networks_interval to 300000 [08:10:04:INFO] [000:563] [30487] (p2p_transport_channel.cc:566): Set receiving_switching_delay to 1000 [08:10:04:INFO] [000:563] [30487] (jsep_transport_controller.cc:1213): Creating DtlsSrtpTransport. [08:10:04:INFO] [000:563] [30487] (dtls_srtp_transport.cc:61): Setting RTCP Transport on 1 transport 0 [08:10:04:INFO] [000:563] [30487] (dtls_srtp_transport.cc:66): Setting RTP Transport on 1 transport d1d39400 [08:10:04:INFO] [000:564] [30487] (p2p_transport_channel.cc:439): Set ICE ufrag: MgMp pwd: Gs9qLqkIJJoSpdCOmumnuqWK on transport 0 [08:10:04:INFO] [000:564] [30487] (p2p_transport_channel.cc:439): Set ICE ufrag: MgMp pwd: Gs9qLqkIJJoSpdCOmumnuqWK on transport 1 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:1492): Setting voice channel options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:04:INFO] [000:565] [27919] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:04:INFO] [000:565] [27919] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:565] [27919] (audio_device_module_ios.mm:600): output: 0 [08:10:04:INFO] [000:565] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:565] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:565] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:565] [27919] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:04:INFO] [000:565] [27919] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:04:INFO] [000:565] [27919] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:565] [27919] (audio_device_module_ios.mm:616): output: 0 [08:10:04:INFO] [000:565] [27919] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:04:INFO] [000:565] [27919] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:565] [27919] (audio_device_module_ios.mm:632): output: 0 [08:10:04:INFO] [000:565] [27919] (apm_helpers.cc:62): NS set to 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:04:INFO] [000:565] [27919] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:04:INFO] [000:565] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:565] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:566] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:566] [27919] (webrtc_voice_engine.cc:1510): Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:566] [27919] (channel.cc:145): Created channel for 0 [08:10:04:INFO] [000:567] [27919] (channel.cc:214): BaseChannel::Init_w, media_transport_config={media_transport: null} [08:10:04:INFO] [000:568] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:568] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:568] [27919] (webrtc_video_engine.cc:492): CreateMediaChannel. Options: VideoOptions {} [08:10:04:INFO] [000:569] [27919] (channel.cc:145): Created channel for 1 [08:10:04:INFO] [000:569] [27919] (channel.cc:214): BaseChannel::Init_w, media_transport_config={media_transport: null} [08:10:04:INFO] [000:570] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:570] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:570] [50187] (peer_connection.cc:4185): Session: 270370254968405980 Old state: kStable New state: kHaveLocalOffer [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:374 -[ARDAppClient peerConnection:didChangeSignalingState:]): Signaling state changed: 1 [08:10:04:INFO] [000:570] [27919] (channel.cc:875): Setting local voice description [08:10:04:INFO] [000:570] [27919] (webrtc_voice_engine.cc:1331): WebRtcVoiceMediaChannel::SetRecvParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[104:ISAC:32000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[102:ILBC:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[106:CN:32000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000:0:1], AudioCodec[112:telephone-event:32000:0:1], AudioCodec[113:telephone-event:16000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [{uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}]} [08:10:04:INFO] [000:570] [27919] (webrtc_voice_engine.cc:1520): Setting receive voice codecs. [08:10:04:INFO] [000:571] [27919] (webrtc_voice_engine.cc:1791): AddSendStream: {id:ARDAMSa0;ssrcs:[2255579466];ssrc_groups:;cname:ZUkKncj912NvzTaZ;stream_ids:ARDAMS;} [08:10:04:INFO] [000:575] [27919] (neteq_impl.cc:123): NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=false, enable_rtx_handling=false [08:10:04:INFO] [000:576] [27919] (audio_coding_module.cc:311): Created [08:10:04:INFO] [000:578] [27919] (channel_send.cc:700): Not setting media_transport_ rate observers. [08:10:04:INFO] [000:579] [27919] (audio_send_stream.cc:142): AudioSendStream: 2255579466 [08:10:04:INFO] [000:579] [27919] (audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 2255579466, extmap-allow-mixed: false, extensions: [], c_name: ZUkKncj912NvzTaZ}, rtcp_report_interval_ms: 5000, send_transport: (Transport), media_transport_config: {media_transport: null}, min_bitrate_bps: -1, max_bitrate_bps: -1, send_codec_spec: <unset>} [08:10:04:INFO] [000:580] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:580] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:580] [27919] (channel.cc:678): Add send stream ssrc: 2255579466 [08:10:04:INFO] [000:580] [27919] (channel.cc:867): Changing voice state, recv=0 send=0 [08:10:04:INFO] [000:581] [27919] (channel.cc:1013): Setting local video description [08:10:04:INFO] [000:581] [27919] (webrtc_video_engine.cc:965): SetRecvParameters: {codecs: [VideoCodec[96:H264], VideoCodec[97:rtx], VideoCodec[98:H264], VideoCodec[99:rtx], VideoCodec[100:VP8], VideoCodec[101:rtx], VideoCodec[127:VP9], VideoCodec[123:rtx], VideoCodec[125:red], VideoCodec[122:rtx], VideoCodec[124:ulpfec]], extensions: [{uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}]} [08:10:04:INFO] [000:582] [27919] (webrtc_video_engine.cc:980): Changing recv codecs from {VideoCodec[96:H264], VideoCodec[98:H264], VideoCodec[100:VP8], VideoCodec[102:VP9]} to {VideoCodec[96:H264], VideoCodec[98:H264], VideoCodec[100:VP8], VideoCodec[127:VP9]} [08:10:04:INFO] [000:582] [27919] (webrtc_video_engine.cc:1081): AddSendStream: {id:ARDAMSv0;ssrcs:[617989588,1547243348];ssrc_groups:{semantics:FID;ssrcs:[617989588,1547243348]};cname:ZUkKncj912NvzTaZ;stream_ids:ARDAMS;} [08:10:04:INFO] [000:582] [27919] (webrtc_video_engine.cc:1127): SetLocalSsrc on all the receive streams because we added a send stream. [08:10:04:INFO] [000:582] [27919] (channel.cc:678): Add send stream ssrc: 617989588 [08:10:04:INFO] [000:583] [27919] (channel.cc:1000): Changing video state, send=0 [08:10:04:INFO] [000:583] [50187] (peer_connection.cc:5531): Local and Remote descriptions must be applied to get the SSL Role of the SCTP transport. [08:10:04:INFO] [000:583] [27919] (webrtc_voice_engine.cc:1492): Setting voice channel options: AudioOptions {} [08:10:04:INFO] [000:583] [27919] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:584] [27919] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:04:INFO] [000:584] [27919] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:04:INFO] [000:584] [27919] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:04:INFO] [000:584] [27919] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:04:INFO] [000:584] [27919] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:584] [27919] (audio_device_module_ios.mm:600): output: 0 [08:10:04:INFO] [000:584] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:584] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:584] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:584] [27919] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:04:INFO] [000:585] [27919] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:04:INFO] [000:585] [27919] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:585] [27919] (audio_device_module_ios.mm:616): output: 0 [08:10:04:INFO] [000:585] [27919] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:04:INFO] [000:585] [27919] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:585] [27919] (audio_device_module_ios.mm:632): output: 0 [08:10:04:INFO] [000:585] [27919] (apm_helpers.cc:62): NS set to 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:04:INFO] [000:585] [27919] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:04:INFO] [000:585] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:586] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:586] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:586] [27919] (webrtc_voice_engine.cc:1510): Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:586] [27919] (webrtc_video_engine.cc:1040): SetVideoSend (ssrc= 617989588, options: VideoOptions {noise reduction: false, is_screencast : false, }, source = (source)) [08:10:04:INFO] [000:586] [30487] (basic_port_allocator.cc:405): Start getting ports with prune_turn_ports disabled [08:10:04:INFO] [000:586] [30487] (basic_port_allocator.cc:405): Start getting ports with prune_turn_ports disabled [08:10:04:INFO] [000:587] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:414 -[ARDAppClient peerConnection:didChangeIceGatheringState:]): ICE gathering state changed: 1 [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:109): Filtered out ignored networks: [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:111): Net[lo0:::1/128:Loopback:id=3] [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:111): Net[lo0:127.0.0.0/8:Loopback:id=2] [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:918): Network manager has started [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:109): Filtered out ignored networks: [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:111): Net[lo0:::1/128:Loopback:id=3] [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:111): Net[lo0:127.0.0.0/8:Loopback:id=2] [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:918): Network manager has started [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:109): Filtered out ignored networks: [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:111): Net[lo0:::1/128:Loopback:id=3] [08:10:04:INFO] [000:588] [30487] (basic_port_allocator.cc:111): Net[lo0:127.0.0.0/8:Loopback:id=2] [08:10:04:INFO] [000:588] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:04:INFO] [000:593] [30487] (basic_port_allocator.cc:833): Allocate ports on 1 networks [08:10:04:INFO] [000:594] [30487] (basic_port_allocator.cc:109): Filtered out ignored networks: [08:10:04:INFO] [000:594] [30487] (basic_port_allocator.cc:111): Net[lo0:::1/128:Loopback:id=3] [08:10:04:INFO] [000:594] [30487] (basic_port_allocator.cc:111): Net[lo0:127.0.0.0/8:Loopback:id=2] [08:10:04:INFO] [000:594] [30487] (basic_port_allocator.cc:833): Allocate ports on 1 networks [08:10:04:INFO] [000:594] [30487] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Udp [08:10:04:INFO] [000:594] [30487] (port.cc:321): Port[d3827800::1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port created with network cost 10 [08:10:04:INFO] [000:595] [30487] (basic_port_allocator.cc:942): Adding allocated port for 0 [08:10:04:INFO] [000:595] [30487] (basic_port_allocator.cc:961): Port[d3827800:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Added port to allocator [08:10:04:INFO] [000:595] [30487] (basic_port_allocator.cc:979): Port[d3827800:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Gathered candidate: Cand[:2826236555:1:udp:2122260223:192.168.72.60:63177:local::0:MgMp:Gs9qLqkIJJoSpdCOmumnuqWK:1:10:0] [08:10:04:INFO] [000:596] [34059] (p2p_transport_channel.cc:502): Set backup connection ping interval to 25000 milliseconds. [08:10:04:INFO] [000:599] [34059] (p2p_transport_channel.cc:511): Set ICE receiving timeout to 2500 milliseconds [08:10:04:INFO] [000:599] [30487] (basic_port_allocator.cc:1007): Port[d3827800:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port ready. [08:10:04:INFO] [000:599] [34059] (p2p_transport_channel.cc:518): Set ping most likely connection to 0 [08:10:04:INFO] [000:599] [30487] (physical_socket_server.cc:553): Socket::OPT_DSCP not supported. [08:10:04:INFO] [000:599] [34059] (p2p_transport_channel.cc:525): Set stable_writable_connection_ping_interval to 2500 [08:10:04:INFO] [000:599] [30487] (p2p_transport_channel.cc:806): Port[d3827800:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: SetOption(5, 0) failed: 0 [08:10:04:INFO] [000:599] [34059] (p2p_transport_channel.cc:538): Set presume writable when fully relayed to 0 [08:10:04:INFO] [000:599] [30487] (basic_port_allocator.cc:1084): Port[d3827800:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port completed gathering candidates. [08:10:04:INFO] [000:599] [34059] (p2p_transport_channel.cc:547): Set regather_on_failed_networks_interval to 300000 [08:10:04:INFO] [000:599] [34059] (p2p_transport_channel.cc:566): Set receiving_switching_delay to 1000 [08:10:04:INFO] [000:599] [30487] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Udp [08:10:04:INFO] [000:599] [34059] (jsep_transport_controller.cc:1213): Creating DtlsSrtpTransport. [08:10:04:INFO] [000:599] [30487] (port.cc:321): Port[d3095000::1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port created with network cost 10 [08:10:04:INFO] [000:600] [34059] (dtls_srtp_transport.cc:61): Setting RTCP Transport on 0 transport 0 [08:10:04:INFO] [000:600] [30487] (basic_port_allocator.cc:942): Adding allocated port for 1 [08:10:04:INFO] [000:600] [34059] (dtls_srtp_transport.cc:66): Setting RTP Transport on 0 transport d1c1b6d0 [08:10:04:INFO] [000:600] [30487] (basic_port_allocator.cc:961): Port[d3095000:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Added port to allocator [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:502): Set backup connection ping interval to 25000 milliseconds. [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:511): Set ICE receiving timeout to 2500 milliseconds [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:518): Set ping most likely connection to 0 [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:525): Set stable_writable_connection_ping_interval to 2500 [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:538): Set presume writable when fully relayed to 0 [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:547): Set regather_on_failed_networks_interval to 300000 [08:10:04:INFO] [000:600] [34059] (p2p_transport_channel.cc:566): Set receiving_switching_delay to 1000 [08:10:04:INFO] [000:600] [30487] (basic_port_allocator.cc:979): Port[d3095000:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Gathered candidate: Cand[:2826236555:1:udp:2122260223:192.168.72.60:56616:local::0:MgMp:Gs9qLqkIJJoSpdCOmumnuqWK:1:10:0] [08:10:04:INFO] [000:600] [34059] (jsep_transport_controller.cc:1213): Creating DtlsSrtpTransport. [08:10:04:INFO] [000:600] [30487] (basic_port_allocator.cc:1007): Port[d3095000:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port ready. [08:10:04:INFO] [000:600] [34059] (dtls_srtp_transport.cc:61): Setting RTCP Transport on 1 transport 0 [08:10:04:INFO] [000:600] [30487] (physical_socket_server.cc:553): Socket::OPT_DSCP not supported. [08:10:04:INFO] [000:601] [34059] (dtls_srtp_transport.cc:66): Setting RTP Transport on 1 transport d1c1c150 [08:10:04:INFO] [000:601] [30487] (p2p_transport_channel.cc:806): Port[d3095000:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: SetOption(5, 0) failed: 0 [08:10:04:INFO] [000:601] [34059] (p2p_transport_channel.cc:450): Received remote ICE parameters: ufrag=MgMp, renomination enabled [08:10:04:INFO] [000:601] [30487] (basic_port_allocator.cc:1084): Port[d3095000:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port completed gathering candidates. [08:10:04:INFO] [000:601] [34059] (p2p_transport_channel.cc:450): Received remote ICE parameters: ufrag=MgMp, renomination enabled [08:10:04:INFO] [000:601] [50691] (webrtc_voice_engine.cc:1492): Setting voice channel options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:602] [50691] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:602] [50691] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:04:INFO] [000:602] [50691] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:04:INFO] [000:602] [50691] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:04:INFO] [000:602] [50691] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:04:INFO] [000:602] [50691] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:602] [50691] (audio_device_module_ios.mm:600): output: 0 [08:10:04:INFO] [000:602] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:602] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:602] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:602] [50691] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:04:INFO] [000:602] [50691] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:04:INFO] [000:602] [50691] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:603] [50691] (audio_device_module_ios.mm:616): output: 0 [08:10:04:INFO] [000:603] [50691] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:04:INFO] [000:603] [50691] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:603] [50691] (audio_device_module_ios.mm:632): output: 0 [08:10:04:INFO] [000:603] [50691] (apm_helpers.cc:62): NS set to 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:04:INFO] [000:603] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:603] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:603] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:603] [50691] (webrtc_voice_engine.cc:1510): Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:603] [50691] (channel.cc:145): Created channel for 0 [08:10:04:INFO] [000:603] [50691] (channel.cc:214): BaseChannel::Init_w, media_transport_config={media_transport: null} [08:10:04:INFO] [000:603] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:603] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:604] [50691] (webrtc_video_engine.cc:492): CreateMediaChannel. Options: VideoOptions {} [08:10:04:INFO] [000:604] [50691] (channel.cc:145): Created channel for 1 [08:10:04:INFO] [000:604] [50691] (channel.cc:214): BaseChannel::Init_w, media_transport_config={media_transport: null} [08:10:04:INFO] [000:605] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:605] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:605] [50947] (peer_connection.cc:4185): Session: 7061599334522676903 Old state: kStable New state: kHaveRemoteOffer [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:374 -[ARDAppClient peerConnection:didChangeSignalingState:]): Signaling state changed: 3 [08:10:04:INFO] [000:605] [50691] (channel.cc:927): Setting remote voice description [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:1286): WebRtcVoiceMediaChannel::SetSendParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[104:ISAC:32000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[102:ILBC:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[106:CN:32000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000:0:1], AudioCodec[112:telephone-event:32000:0:1], AudioCodec[113:telephone-event:16000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [{uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 0, options: AudioOptions {}} [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:1709): Recreate all the receive streams because the send codec has changed. [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:2170): WebRtcVoiceMediaChannel::SetMaxSendBitrate. [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:1492): Setting voice channel options: AudioOptions {} [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:04:INFO] [000:605] [50691] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:04:INFO] [000:605] [50691] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:04:INFO] [000:605] [50691] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:605] [50691] (audio_device_module_ios.mm:600): output: 0 [08:10:04:INFO] [000:605] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:606] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:606] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:606] [50691] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:04:INFO] [000:606] [50691] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:04:INFO] [000:606] [50691] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:606] [50691] (audio_device_module_ios.mm:616): output: 0 [08:10:04:INFO] [000:606] [50691] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:04:INFO] [000:606] [50691] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:04:INFO] [000:606] [50691] (audio_device_module_ios.mm:632): output: 0 [08:10:04:INFO] [000:606] [50691] (apm_helpers.cc:62): NS set to 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:04:INFO] [000:606] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:04:INFO] [000:606] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:04:INFO] [000:606] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:04:INFO] [000:606] [50691] (webrtc_voice_engine.cc:1510): Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:04:INFO] [000:607] [50691] (webrtc_voice_engine.cc:1856): AddRecvStream: {id:ARDAMSa0;ssrcs:[2255579466];ssrc_groups:;cname:ZUkKncj912NvzTaZ;stream_ids:ARDAMS;} [08:10:04:INFO] [000:607] [50691] (neteq_impl.cc:123): NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=true, enable_rtx_handling=false [08:10:04:INFO] [000:608] [50691] (audio_coding_module.cc:311): Created [08:10:04:INFO] [000:608] [50691] (audio_receive_stream.cc:117): AudioReceiveStream: 2255579466 [08:10:04:INFO] [000:608] [50691] (audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 2255579466, local_ssrc: 4195875351, transport_cc: on, nack: {rtp_history_ms: 0}, extensions: []}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}, sync_group: ARDAMS} [08:10:04:INFO] [000:608] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:608] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:608] [50691] (channel.cc:721): Add remote ssrc: 2255579466 [08:10:04:INFO] [000:609] [50691] (channel.cc:867): Changing voice state, recv=0 send=0 [08:10:04:INFO] [000:609] [50691] (channel.cc:1065): Setting remote video description [08:10:04:INFO] [000:609] [50691] (webrtc_video_engine.cc:690): SetSendParameters: {codecs: [VideoCodec[96:H264], VideoCodec[97:rtx], VideoCodec[98:H264], VideoCodec[99:rtx], VideoCodec[100:VP8], VideoCodec[101:rtx], VideoCodec[127:VP9], VideoCodec[123:rtx], VideoCodec[125:red], VideoCodec[122:rtx], VideoCodec[124:ulpfec]], conference_mode: no, extensions: [{uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 1} [08:10:04:INFO] [000:610] [50691] (webrtc_video_engine.cc:699): Using codec: VideoCodec[96:H264] [08:10:04:INFO] [000:610] [50691] (webrtc_video_engine.cc:767): SetFeedbackOptions on all the receive streams because the send codec or RTCP mode has changed. [08:10:04:INFO] [000:610] [50691] (webrtc_video_engine.cc:1190): AddRecvStream: {id:ARDAMSv0;ssrcs:[617989588,1547243348];ssrc_groups:{semantics:FID;ssrcs:[617989588,1547243348]};cname:ZUkKncj912NvzTaZ;stream_ids:ARDAMS;} [08:10:04:INFO] [000:616] [50691] (video_receive_stream.cc:220): VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 102, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 617989588, local_ssrc: 1, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 106, red_type: 104, rtx_ssrc: 1547243348, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 103 (pt) -> 102 (apt), 105 (pt) -> 104 (apt), }, raw_payload_types: {}, extensions: []}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:04:INFO] [000:617] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:617] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:620] [50691] (channel.cc:721): Add remote ssrc: 617989588 [08:10:04:INFO] [000:620] [50691] (channel.cc:1000): Changing video state, send=0 [08:10:04:INFO] [000:620] [50947] (peer_connection.cc:5531): Local and Remote descriptions must be applied to get the SSL Role of the SCTP transport. [08:10:04:INFO] [000:620] [50947] (peer_connection.cc:2727): Processing the MSIDs for MID=0 (streams=[ARDAMS]). [08:10:04:INFO] [000:620] [50947] (peer_connection.cc:2738): Processing the addition of a remote track for MID=0. [08:10:04:INFO] [000:621] [50691] (webrtc_voice_engine.cc:1969): SetOutputVolume() to 1 for recv stream with ssrc 2255579466 [08:10:04:INFO] [000:621] [50947] (peer_connection.cc:2727): Processing the MSIDs for MID=1 (streams=[ARDAMS]). [08:10:04:INFO] [000:621] [50947] (peer_connection.cc:2738): Processing the addition of a remote track for MID=1. [08:10:04:INFO] [000:621] [50691] (webrtc_video_engine.cc:1320): SetSink: ssrc:617989588 (ptr) [08:10:04:INFO] [000:621] [50947] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f14880): created DTMF sender: RTCDtmfSender { [08:10:04:INFO] remainingTones: [08:10:04:INFO] duration: 0.100000 sec [08:10:04:INFO] interToneGap: 0.050000 sec [08:10:04:INFO] } [08:10:04:INFO] [000:621] [50947] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d27e60): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] [000:621] [50947] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x60000033f1e0): created receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 7908a7ac-87c3-49a4-9da5-94e32eec92d4 [08:10:04:INFO] } [08:10:04:INFO] [000:621] [50947] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x60000033f000): created transceiver: RTCRtpTransceiver { [08:10:04:INFO] sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 7908a7ac-87c3-49a4-9da5-94e32eec92d4 [08:10:04:INFO] } [08:10:04:INFO] } [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:387 -[ARDAppClient peerConnection:didStartReceivingOnTransceiver:]): Now receiving audio on track 7908a7ac-87c3-49a4-9da5-94e32eec92d4. [08:10:04:INFO] [000:621] [50947] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:04:INFO] [000:621] [50947] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d27440): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] [000:622] [50947] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x60000033f390): created receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 6e6ae9cd-17fd-458f-8eb6-ace431a0f684 [08:10:04:INFO] } [08:10:04:INFO] [000:622] [50947] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x60000033f000): created transceiver: RTCRtpTransceiver { [08:10:04:INFO] sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] receiver: RTCRtpReceiver { [08:10:04:INFO] receiverId: 6e6ae9cd-17fd-458f-8eb6-ace431a0f684 [08:10:04:INFO] } [08:10:04:INFO] } [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:387 -[ARDAppClient peerConnection:didStartReceivingOnTransceiver:]): Now receiving video on track 6e6ae9cd-17fd-458f-8eb6-ace431a0f684. [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:381 -[ARDAppClient peerConnection:didAddStream:]): Stream with 1 video tracks and 1 audio tracks was added. [08:10:04:INFO] [000:622] [9987] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f0eb10): created DTMF sender: RTCDtmfSender { [08:10:04:INFO] remainingTones: [08:10:04:INFO] duration: 0.100000 sec [08:10:04:INFO] interToneGap: 0.050000 sec [08:10:04:INFO] } [08:10:04:INFO] [000:622] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d27e40): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSa0 [08:10:04:INFO] } [08:10:04:INFO] [000:622] [50187] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:04:INFO] [000:622] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d20840): created sender: RTCRtpSender { [08:10:04:INFO] senderId: ARDAMSv0 [08:10:04:INFO] } [08:10:04:INFO] [000:624] [50947] (peer_connection.cc:6224): 0 is not ready to use the remote candidate because the local or remote description is not set. [08:10:04:INFO] [000:624] [50947] (peer_connection.cc:6224): 1 is not ready to use the remote candidate because the local or remote description is not set. [08:10:04:INFO] [000:625] [50947] (peer_connection.cc:2092): CreateAnswer: offer_to_receive_audio is not supported with Unified Plan semantics. Use the RtpTransceiver API instead. [08:10:04:INFO] [000:625] [50947] (peer_connection.cc:2097): CreateAnswer: offer_to_receive_video is not supported with Unified Plan semantics. Use the RtpTransceiver API instead. [08:10:04:INFO] [000:625] [50947] (peer_connection.cc:5559): Local and Remote descriptions must be applied to get the SSL Role of the session. [08:10:04:INFO] [000:625] [50947] (peer_connection.cc:5559): Local and Remote descriptions must be applied to get the SSL Role of the session. [08:10:04:INFO] [000:628] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:04:INFO] [000:628] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:04:INFO] [000:631] [34059] (dtls_srtp_transport.cc:61): Setting RTCP Transport on 0 transport 0 [08:10:04:INFO] [000:631] [34059] (dtls_srtp_transport.cc:66): Setting RTP Transport on 0 transport d1c1b6d0 [08:10:04:INFO] [000:633] [34059] (p2p_transport_channel.cc:439): Set ICE ufrag: hhNr pwd: K7hXyO13lV4Ajm6pgOicq/gW on transport 0 [08:10:04:INFO] [000:634] [34059] (dtls_transport.cc:370): DtlsTransport[0|1|__]: DTLS setup complete. [08:10:04:INFO] [000:634] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:634] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:634] [50947] (rtp_transceiver.cc:174): Changing transceiver (MID=0) current direction from <not set> to kSendRecv. [08:10:04:INFO] [000:634] [50947] (rtp_transceiver.cc:174): Changing transceiver (MID=1) current direction from <not set> to kSendRecv. [08:10:04:INFO] [000:634] [50691] (channel.cc:555): Channel enabled [08:10:04:INFO] [000:634] [50691] (channel.cc:867): Changing voice state, recv=0 send=0 [08:10:04:INFO] [000:634] [50691] (channel.cc:555): Channel enabled [08:10:04:INFO] [000:634] [50691] (channel.cc:1000): Changing video state, send=0 [08:10:04:INFO] [000:634] [50947] (peer_connection.cc:4185): Session: 7061599334522676903 Old state: kHaveRemoteOffer New state: kStable [08:10:04:INFO] (RTCLogging.mm:33): (ARDAppClient.m:374 -[ARDAppClient peerConnection:didChangeSignalingState:]): Signaling state changed: 0 [08:10:04:INFO] [000:634] [50691] (channel.cc:875): Setting local voice description [08:10:04:INFO] [000:637] [50691] (webrtc_voice_engine.cc:1331): WebRtcVoiceMediaChannel::SetRecvParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[104:ISAC:32000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[102:ILBC:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[106:CN:32000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000:0:1], AudioCodec[112:telephone-event:32000:0:1], AudioCodec[113:telephone-event:16000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [{uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}]} [08:10:04:INFO] [000:637] [50691] (webrtc_voice_engine.cc:1520): Setting receive voice codecs. [08:10:04:INFO] [000:637] [50691] (audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 2255579466, local_ssrc: 4195875351, transport_cc: on, nack: {rtp_history_ms: 0}, extensions: []}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}, sync_group: ARDAMS} [08:10:04:INFO] [000:638] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:638] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:638] [50691] (audio_receive_stream.cc:140): ~AudioReceiveStream: 2255579466 [08:10:04:INFO] [000:638] [50691] (neteq_impl.cc:123): NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=true, enable_rtx_handling=false [08:10:04:INFO] [000:638] [50691] (audio_coding_module.cc:311): Created [08:10:04:INFO] [000:638] [50691] (audio_receive_stream.cc:117): AudioReceiveStream: 2255579466 [08:10:04:INFO] [000:638] [50691] (audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 2255579466, local_ssrc: 4195875351, transport_cc: on, nack: {rtp_history_ms: 0}, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}]}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}, sync_group: ARDAMS} [08:10:04:INFO] [000:638] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:638] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:639] [50691] (webrtc_voice_engine.cc:1791): AddSendStream: {id:ARDAMSa0;ssrcs:[4124656428];ssrc_groups:;cname:eqki72hWABX2mav5;stream_ids:ARDAMS;} [08:10:04:INFO] [000:639] [50691] (neteq_impl.cc:123): NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=false, enable_rtx_handling=false [08:10:04:INFO] [000:639] [50691] (audio_coding_module.cc:311): Created [08:10:04:INFO] [000:639] [50691] (channel_send.cc:700): Not setting media_transport_ rate observers. [08:10:04:INFO] [000:639] [50691] (audio_send_stream.cc:142): AudioSendStream: 4124656428 [08:10:04:INFO] [000:639] [50691] (audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 4124656428, extmap-allow-mixed: false, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}], c_name: eqki72hWABX2mav5}, rtcp_report_interval_ms: 5000, send_transport: (Transport), media_transport_config: {media_transport: null}, min_bitrate_bps: 32000, max_bitrate_bps: 32000, send_codec_spec: {nack_enabled: false, transport_cc_enabled: true, cng_payload_type: <unset>, payload_type: 111, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {minptime: 10, useinbandfec: 1}}}} [08:10:04:INFO] [000:642] [50691] (audio_encoder_opus.cc:767): Set Opus bitrate to 32000 bps. [08:10:04:INFO] [000:643] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:04:INFO] [000:643] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:04:INFO] [000:643] [50691] (audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 2255579466, local_ssrc: 4124656428, transport_cc: on, nack: {rtp_history_ms: 0}, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}]}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}, sync_group: ARDAMS} [08:10:04:INFO] [000:643] [50691] (channel.cc:678): Add send stream ssrc: 4124656428 [08:10:04:INFO] [000:643] [50691] (audio_device_module_ios.mm:543): Playing [08:10:04:INFO] [000:643] [50691] (audio_device_module_ios.mm:480): InitPlayout [08:10:04:INFO] [000:643] [50691] (audio_device_module_ios.mm:506): PlayoutIsInitialized [08:10:04:INFO] [000:643] [50691] (audio_device_ios.mm:185): AudioDeviceIOS::InitPlayout [08:10:04:INFO] [000:643] [50691] (audio_device_ios.mm:860): AudioDeviceIOS::InitPlayOrRecord [08:10:04:INFO] [000:655] [30487] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Relay [08:10:04:INFO] [000:655] [30487] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Relay [08:10:04:INFO] [000:683] [50691] (RTCLogging.mm:33): (RTCAudioSession.mm:101 -[RTCAudioSession initWithAudioSession:]): RTCAudioSession (0x600003f08120): init. [08:10:04:INFO] [000:683] [50691] (RTCLogging.mm:33): (audio_device_ios.mm:825 ConfigureAudioSession): Configuring audio session. [08:10:05:INFO] [000:683] [50691] (RTCLogging.mm:33): (RTCAudioSession.mm:718 -[RTCAudioSession configureWebRTCSession:]): Configuring audio session for WebRTC. [08:10:05:INFO] [000:684] [50691] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:64 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set category to: AVAudioSessionCategoryPlayAndRecord [08:10:05:INFO] [000:684] [50691] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:75 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set mode to: AVAudioSessionModeVoiceChat [08:10:05:INFO] [000:684] [50691] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:105 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set preferred sample rate to: 48000.00 [08:10:05:INFO] [000:684] [50691] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:120 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set preferred IO buffer duration to: 0.020000 [08:10:05:INFO] [000:684] [50691] (RTCLogging.mm:33): (RTCAudioSession.mm:643 -[RTCAudioSession incrementActivationCount]): Incrementing activation count. [08:10:05:INFO] [000:684] [50691] (RTCLogging.mm:33): (RTCAudioSession.mm:396 -[RTCAudioSession setActive:error:]): Number of current activations: 1 [08:10:05:INFO] [000:685] [50691] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:151 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set input number of channels to: 1 [08:10:05:INFO] [000:685] [50691] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:166 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set output number of channels to: 1 [08:10:05:INFO] [000:685] [50691] (RTCLogging.mm:33): (audio_device_ios.mm:836 ConfigureAudioSession): Configured audio session. [08:10:05:INFO] [000:685] [50691] (audio_device_ios.mm:679): AudioDeviceIOS::SetupAudioBuffersForActiveAudioSession [08:10:05:INFO] [000:685] [50691] (RTCLogging.mm:33): (audio_device_ios.mm:684 SetupAudioBuffersForActiveAudioSession): RTCAudioSession: { [08:10:05:INFO] category: AVAudioSessionCategoryPlayAndRecord [08:10:05:INFO] categoryOptions: 4 [08:10:05:INFO] mode: AVAudioSessionModeVoiceChat [08:10:05:INFO] isActive: 1 [08:10:05:INFO] sampleRate: 48000.00 [08:10:05:INFO] IOBufferDuration: 0.010667 [08:10:05:INFO] outputNumberOfChannels: 2 [08:10:05:INFO] inputNumberOfChannels: 2 [08:10:05:INFO] outputLatency: 0.010000 [08:10:05:INFO] inputLatency: 0.010000 [08:10:05:INFO] outputVolume: 0.600000 [08:10:05:INFO] } [08:10:05:INFO] [000:685] [50691] (audio_device_ios.mm:715): frames per I/O buffer: 512 [08:10:05:INFO] [000:685] [50691] (audio_device_ios.mm:716): bytes per I/O buffer: 1024 [08:10:05:INFO] [000:685] [50691] (audio_device_ios.mm:663): AudioDeviceIOS::UpdateAudioDevicebuffer [08:10:05:INFO] [000:685] [50691] (audio_device_buffer.cc:186): SetPlayoutSampleRate(48000) [08:10:05:INFO] [000:685] [50691] (audio_device_buffer.cc:206): SetPlayoutChannels(1) [08:10:05:INFO] [000:685] [50691] (audio_device_buffer.cc:180): SetRecordingSampleRate(48000) [08:10:05:INFO] [000:685] [50691] (audio_device_buffer.cc:200): SetRecordingChannels(1) [08:10:05:INFO] [000:685] [50691] (fine_audio_buffer.cc:32): FineAudioBuffer [08:10:05:INFO] [000:685] [50691] (fine_audio_buffer.cc:34): playout_samples_per_channel_10ms: 480 [08:10:05:INFO] [000:685] [50691] (fine_audio_buffer.cc:36): playout_channels: 1 [08:10:05:INFO] [000:685] [50691] (fine_audio_buffer.cc:39): record_samples_per_channel_10ms: 480 [08:10:05:INFO] [000:685] [50691] (fine_audio_buffer.cc:41): record_channels: 1 [08:10:05:INFO] [000:686] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:196 Initialize): Initializing audio unit with sample rate: 48000.000000 [08:10:05:INFO] [000:686] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:43 LogStreamDescription): AudioStreamBasicDescription: { [08:10:05:INFO] mSampleRate: 48000.00 [08:10:05:INFO] formatIDString: lpcm [08:10:05:INFO] mFormatFlags: 0xC [08:10:05:INFO] mBytesPerPacket: 2 [08:10:05:INFO] mFramesPerPacket: 1 [08:10:05:INFO] mBytesPerFrame: 2 [08:10:05:INFO] mChannelsPerFrame: 1 [08:10:05:INFO] mBitsPerChannel: 16 [08:10:05:INFO] mReserved: 28672 [08:10:05:INFO] } [08:10:05:INFO] [000:711] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:250 Initialize): Voice Processing I/O unit is now initialized. [08:10:05:INFO] [000:712] [30487] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Tcp [08:10:05:INFO] [000:712] [30487] (port.cc:321): Port[d28b6600::1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port created with network cost 10 [08:10:05:INFO] [000:712] [30487] (basic_port_allocator.cc:942): Adding allocated port for 0 [08:10:05:INFO] [000:712] [30487] (basic_port_allocator.cc:961): Port[d28b6600:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Added port to allocator [08:10:05:INFO] [000:713] [30487] (basic_port_allocator.cc:979): Port[d28b6600:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Gathered candidate: Cand[:3874950779:1:tcp:1518280447:192.168.72.60:50491:local::0:MgMp:Gs9qLqkIJJoSpdCOmumnuqWK:1:10:0] [08:10:05:INFO] [000:713] [30487] (basic_port_allocator.cc:1007): Port[d28b6600:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port ready. [08:10:05:INFO] [000:713] [30487] (physical_socket_server.cc:553): Socket::OPT_DSCP not supported. [08:10:05:INFO] [000:713] [30487] (p2p_transport_channel.cc:806): Port[d28b6600:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: SetOption(5, 0) failed: 0 [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:1084): Port[d28b6600:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port completed gathering candidates. [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:1159): All candidates gathered for 0:1:0 [08:10:05:INFO] [000:714] [30487] (p2p_transport_channel.cc:861): P2PTransportChannel: 0, component 1 gathering complete [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Tcp [08:10:05:INFO] [000:714] [30487] (port.cc:321): Port[d210f400::1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port created with network cost 10 [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:942): Adding allocated port for 1 [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:961): Port[d210f400:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Added port to allocator [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:979): Port[d210f400:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Gathered candidate: Cand[:3874950779:1:tcp:1518280447:192.168.72.60:50492:local::0:MgMp:Gs9qLqkIJJoSpdCOmumnuqWK:1:10:0] [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:1007): Port[d210f400:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port ready. [08:10:05:INFO] [000:714] [30487] (physical_socket_server.cc:553): Socket::OPT_DSCP not supported. [08:10:05:INFO] [000:714] [30487] (p2p_transport_channel.cc:806): Port[d210f400:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: SetOption(5, 0) failed: 0 [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:1084): Port[d210f400:1:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port completed gathering candidates. [08:10:05:INFO] [000:714] [30487] (basic_port_allocator.cc:1159): All candidates gathered for 1:1:0 [08:10:05:INFO] [000:714] [30487] (p2p_transport_channel.cc:861): P2PTransportChannel: 1, component 1 gathering complete [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:414 -[ARDAppClient peerConnection:didChangeIceGatheringState:]): ICE gathering state changed: 2 [08:10:05:INFO] [000:833] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:71 GetAGCState): VPIO unit AGC: 0 [08:10:05:INFO] [000:833] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:264 Initialize): Failed to get AGC state (1st attempt). Error=-66631. [08:10:05:INFO] [000:833] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:302 Initialize): WebRTC.Audio.BuiltInAGCWasEnabledByDefault: 0 [08:10:05:INFO] [000:834] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:308 Initialize): WebRTC.Audio.BuiltInAGCIsEnabled: 0 [08:10:05:INFO] [000:834] [50691] (audio_device_module_ios.mm:486): output: 0 [08:10:05:INFO] [000:834] [50691] (audio_device_module_ios.mm:518): StartPlayout [08:10:05:INFO] [000:834] [50691] (audio_device_module_ios.mm:543): Playing [08:10:05:INFO] [000:834] [50691] (audio_device_buffer.cc:98): StartPlayout [08:10:05:INFO] [000:834] [50691] (audio_device_ios.mm:227): AudioDeviceIOS::StartPlayout [08:10:05:INFO] [000:834] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:316 Start): Starting audio unit. [08:10:05:INFO] [000:844] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:320 Start): Failed to start audio unit. Error=-66637 [08:10:05:INFO] [000:844] [50691] (RTCLogging.mm:33): (audio_device_ios.mm:237 StartPlayout): StartPlayout failed to start audio unit. [08:10:05:INFO] [000:844] [50691] (audio_device_module_ios.mm:525): output: -1 [08:10:05:INFO] [000:844] [50691] (channel.cc:867): Changing voice state, recv=1 send=0 [08:10:05:INFO] [000:844] [50691] (message_queue.cc:517): Message took 210ms to dispatch. Posted from: SetLocalContent@../../pc/channel.cc:295 [08:10:05:INFO] [000:845] [50691] (channel.cc:1013): Setting local video description [08:10:05:INFO] [000:845] [50691] (webrtc_video_engine.cc:965): SetRecvParameters: {codecs: [VideoCodec[96:H264], VideoCodec[97:rtx], VideoCodec[98:H264], VideoCodec[99:rtx], VideoCodec[100:VP8], VideoCodec[101:rtx], VideoCodec[127:VP9], VideoCodec[123:rtx], VideoCodec[125:red], VideoCodec[122:rtx], VideoCodec[124:ulpfec]], extensions: [{uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}]} [08:10:05:INFO] [000:846] [50691] (webrtc_video_engine.cc:980): Changing recv codecs from {VideoCodec[96:H264], VideoCodec[98:H264], VideoCodec[100:VP8], VideoCodec[102:VP9]} to {VideoCodec[96:H264], VideoCodec[98:H264], VideoCodec[100:VP8], VideoCodec[127:VP9]} [08:10:05:INFO] [000:846] [50691] (webrtc_video_engine.cc:2572): MaybeRecreateWebRtcFlexfecStream (recv) because of SetRecvParameters [08:10:05:INFO] [000:846] [50691] (webrtc_video_engine.cc:2577): RecreateWebRtcVideoStream (recv) because of SetRecvParameters [08:10:05:INFO] [000:846] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:846] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:846] [50691] (video_receive_stream.cc:287): ~VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 102, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 617989588, local_ssrc: 1, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 106, red_type: 104, rtx_ssrc: 1547243348, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 103 (pt) -> 102 (apt), 105 (pt) -> 104 (apt), }, raw_payload_types: {}, extensions: []}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:05:INFO] [000:846] [50691] (video_render_frames.cc:47): WebRTC.Video.DroppedFrames.RenderQueue 0 [08:10:05:INFO] [000:847] [50691] (receive_statistics_proxy.cc:491): Frames decoded 0 [08:10:05:INFO] WebRTC.Video.DroppedFrames.Receiver 0 [08:10:05:INFO] [08:10:05:INFO] [000:848] [50691] (video_receive_stream.cc:220): VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 127, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 617989588, local_ssrc: 1, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 124, red_type: 125, rtx_ssrc: 1547243348, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 122 (pt) -> 125 (apt), 123 (pt) -> 127 (apt), }, raw_payload_types: {}, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}]}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:05:INFO] [000:848] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:848] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:849] [50691] (webrtc_video_engine.cc:1081): AddSendStream: {id:ARDAMSv0;ssrcs:[2623606793,3720308880];ssrc_groups:{semantics:FID;ssrcs:[2623606793,3720308880]};cname:eqki72hWABX2mav5;stream_ids:ARDAMS;} [08:10:05:INFO] [000:849] [50691] (webrtc_video_engine.cc:1905): RecreateWebRtcStream (send) because of SetCodec. [08:10:05:INFO] [000:851] [50691] (balanced_degradation_settings.cc:31): Unsupported size, value ignored. [08:10:05:INFO] [000:851] [50691] (alr_experiment.cc:78): Using ALR experiment settings: pacing factor: 1, max pacer queue length: 2875, ALR start bandwidth usage percent: 80, ALR end budget level percent: 40, ALR end budget level percent: -60, ALR experiment group ID: 3 [08:10:05:INFO] [000:852] [21251] (rtp_video_sender.cc:510): Transmitting payload type without picture ID using NACK+ULPFEC is a waste of bandwidth since ULPFEC packets also have to be retransmitted. Disabling ULPFEC. [08:10:05:INFO] [000:852] [21251] (video_send_stream_impl.cc:250): VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [2623606793], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 124, red_payload_type: 125, red_rtx_payload_type: 122}, payload_name: H264, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [3720308880], payload_type: 97}, c_name: eqki72hWABX2mav5}, rtcp_report_interval_ms: 1000, send_transport: (Transport), media_transport: nullptr, render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [08:10:05:INFO] [000:853] [21251] (video_send_stream_impl.cc:278): ERROR: Initial encoder max bitrate = -1 which is <= 0! [08:10:05:INFO] [000:853] [21251] (video_send_stream_impl.cc:280): Using default encoder max bitrate = 10 Mbps [08:10:05:INFO] [000:854] [21251] (video_stream_encoder.cc:609): ConfigureEncoder requested. [08:10:05:INFO] [000:854] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:854] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:854] [50691] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:05:INFO] [000:854] [50691] (webrtc_video_engine.cc:1127): SetLocalSsrc on all the receive streams because we added a send stream. [08:10:05:INFO] [000:854] [50691] (webrtc_video_engine.cc:2509): RecreateWebRtcStream (recv) because of SetLocalSsrc; local_ssrc=2623606793 [08:10:05:INFO] [000:854] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:05:INFO] [000:854] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:854] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:854] [50691] (video_receive_stream.cc:287): ~VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 127, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 617989588, local_ssrc: 1, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 124, red_type: 125, rtx_ssrc: 1547243348, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 122 (pt) -> 125 (apt), 123 (pt) -> 127 (apt), }, raw_payload_types: {}, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}]}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:05:INFO] [000:857] [50691] (video_render_frames.cc:47): WebRTC.Video.DroppedFrames.RenderQueue 0 [08:10:05:INFO] [000:857] [50691] (receive_statistics_proxy.cc:491): Frames decoded 0 [08:10:05:INFO] WebRTC.Video.DroppedFrames.Receiver 0 [08:10:05:INFO] [08:10:05:INFO] [000:858] [50691] (video_receive_stream.cc:220): VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 127, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 617989588, local_ssrc: 2623606793, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 124, red_type: 125, rtx_ssrc: 1547243348, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 122 (pt) -> 125 (apt), 123 (pt) -> 127 (apt), }, raw_payload_types: {}, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}]}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:05:INFO] [000:858] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:858] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:858] [50691] (channel.cc:678): Add send stream ssrc: 2623606793 [08:10:05:INFO] [000:858] [50691] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:05:INFO] [000:858] [50691] (channel.cc:1000): Changing video state, send=0 [08:10:05:INFO] [000:858] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:05:INFO] [000:859] [50947] (peer_connection.cc:4117): Changing IceConnectionState 0 => 1 [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:401 -[ARDAppClient peerConnection:didChangeIceConnectionState:]): ICE state changed: 1 [08:10:05:INFO] [000:859] [34059] (jsep_transport_controller.cc:351): Not adding candidate because the JsepTransport doesn't exist. Ignore it. [08:10:05:INFO] [000:859] [50947] (peer_connection.cc:5537): Non-rejected SCTP m= section is needed to get the SSL Role of the SCTP transport. [08:10:05:INFO] [000:859] [50691] (webrtc_voice_engine.cc:1492): Setting voice channel options: AudioOptions {} [08:10:05:INFO] [000:859] [50691] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:05:INFO] [000:859] [50691] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:05:INFO] [000:859] [50691] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:05:INFO] [000:859] [50691] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:05:INFO] [000:862] [50691] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:05:INFO] [000:862] [50691] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:05:INFO] [000:862] [50691] (audio_device_module_ios.mm:600): output: 0 [08:10:05:INFO] [000:862] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [000:862] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [000:862] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [000:862] [50691] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:05:INFO] [000:862] [50691] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:05:INFO] [000:862] [50691] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:05:INFO] [000:862] [50691] (audio_device_module_ios.mm:616): output: 0 [08:10:05:INFO] [000:862] [50691] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:05:INFO] [000:862] [50691] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:05:INFO] [000:862] [50691] (audio_device_module_ios.mm:632): output: 0 [08:10:05:INFO] [000:862] [50691] (apm_helpers.cc:62): NS set to 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:05:INFO] [000:862] [50691] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:05:INFO] [000:862] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [000:863] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [000:863] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [000:863] [50691] (webrtc_voice_engine.cc:1510): Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:05:INFO] [000:863] [50691] (webrtc_video_engine.cc:1040): SetVideoSend (ssrc= 2623606793, options: VideoOptions {noise reduction: false, is_screencast : false, }, source = (source)) [08:10:05:INFO] [000:863] [21251] (video_stream_encoder.cc:609): ConfigureEncoder requested. [08:10:05:INFO] [000:863] [34059] (basic_port_allocator.cc:405): Start getting ports with prune_turn_ports disabled [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:109): Filtered out ignored networks: [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:111): Net[lo0:::1/128:Loopback:id=3] [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:111): Net[lo0:127.0.0.0/8:Loopback:id=2] [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:918): Network manager has started [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:109): Filtered out ignored networks: [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:111): Net[lo0:::1/128:Loopback:id=3] [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:111): Net[lo0:127.0.0.0/8:Loopback:id=2] [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:833): Allocate ports on 1 networks [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:1372): Net[en0:192.168.72.0/22:Wifi:id=1]: Allocation Phase=Udp [08:10:05:INFO] [000:864] [34059] (port.cc:321): Port[d28b9200::1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port created with network cost 10 [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:942): Adding allocated port for 0 [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:961): Port[d28b9200:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Added port to allocator [08:10:05:INFO] [000:864] [50947] (message_queue.cc:517): Message took 233ms to dispatch. Posted from: SetLocalDescription@../../api/peer_connection_proxy.h:100 [08:10:05:INFO] [000:864] [34059] (basic_port_allocator.cc:979): Port[d28b9200:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Gathered candidate: Cand[:2826236555:1:udp:2122260223:192.168.72.60:56954:local::0:hhNr:K7hXyO13lV4Ajm6pgOicq/gW:1:10:0] [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:414 -[ARDAppClient peerConnection:didChangeIceGatheringState:]): ICE gathering state changed: 1 [08:10:05:INFO] [000:864] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:05:INFO] [000:865] [34059] (basic_port_allocator.cc:1007): Port[d28b9200:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port ready. [08:10:05:INFO] [000:865] [34059] (physical_socket_server.cc:553): Socket::OPT_DSCP not supported. [08:10:05:INFO] [000:865] [34059] (p2p_transport_channel.cc:806): Port[d28b9200:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: SetOption(5, 0) failed: 0 [08:10:05:INFO] [000:865] [34059] (port.cc:1129): Conn[d28b4400:0:Net[en0:192.168.72.0/22:Wifi:id=1]:ai3VDgRM:1:0:local:udp:192.168.72.60:56954->fnhSDPkz:1:2122260223:local:udp:192.168.72.60:63177|C--W|-|0|0|9115038255631187454|-]: Connection created [08:10:05:INFO] [000:866] [34059] (p2p_transport_channel.cc:1281): Channel[0|1|__]: Created connection with origin: 2, total: 1 [08:10:05:INFO] [000:866] [9987] (webrtc_sdp.cc:3254): Ignored line: a=rtcp:9 IN IP4 0.0.0.0 [08:10:05:INFO] [000:866] [34059] (p2p_transport_channel.cc:1931): Channel[0|1|__]: Transport channel state changed from 0 to 2 [08:10:05:INFO] [000:866] [34059] (jsep_transport_controller.cc:1411): 0 Transport 1 state changed. Check if state is complete. [08:10:05:INFO] [000:866] [34059] (jsep_transport_controller.cc:1411): 0 Transport 1 state changed. Check if state is complete. [08:10:05:INFO] [000:866] [34059] (p2p_transport_channel.cc:1493): Channel[0|1|__]: Have a pingable connection for the first time; starting to ping. [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:409 -[ARDAppClient peerConnection:didChangeConnectionState:]): ICE+DTLS state changed: 1 [08:10:05:INFO] [000:866] [34059] (basic_port_allocator.cc:1084): Port[d28b9200:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Port completed gathering candidates. [08:10:05:INFO] [000:868] [34059] (port.cc:1777): Conn[d28b4400:0:Net[en0:192.168.72.0/22:Wifi:id=1]:ai3VDgRM:1:0:local:udp:192.168.72.60:56954->fnhSDPkz:1:2122260223:local:udp:192.168.72.60:63177|C--W|-|0|0|9115038255631187454|-]: Sent STUN ping, id=4163364b754a2f6e396e3559, use_candidate=0, nomination=0 [08:10:05:INFO] [000:868] [30487] (port.cc:531): Received STUN ping id=4163364b754a2f6e396e3559 from unknown address 192.168.72.60:56954 [08:10:05:INFO] [000:868] [30487] (port.cc:1129): Conn[d20f5200:0:Net[en0:192.168.72.0/22:Wifi:id=1]:1Is9zz7B:1:0:local:udp:192.168.72.60:63177->6DGZ4Ngn:1:1853824767:prflx:udp:192.168.72.60:56954|C--W|-|0|0|7962116751024340479|-]: Connection created [08:10:05:INFO] [000:868] [30487] (p2p_transport_channel.cc:995): Adding connection from peer reflexive candidate: Cand[:1942046532:1:udp:1853824767:192.168.72.60:56954:prflx::0:hhNr::1:10:0] [08:10:05:INFO] [000:868] [30487] (port.cc:856): Port[d3827800:0:1:0:local:Net[en0:192.168.72.0/22:Wifi:id=1]]: Sent STUN ping response, to=192.168.72.60:56954, id=4163364b754a2f6e396e3559 [08:10:05:INFO] [000:868] [34059] (port.cc:1725): Conn[d28b4400:0:Net[en0:192.168.72.0/22:Wifi:id=1]:ai3VDgRM:1:0:local:udp:192.168.72.60:56954->fnhSDPkz:1:2122260223:local:udp:192.168.72.60:63177|C--I|-|0|0|9115038255631187454|-]: Received STUN ping response, id=4163364b754a2f6e396e3559, code=0, rtt=1, pings_since_last_response=4163364b754a2f6e396e3559 [08:10:05:INFO] [000:868] [30487] (p2p_transport_channel.cc:1931): Channel[0|1|R_]: Transport channel state changed from 0 to 2 [08:10:05:INFO] [000:868] [30487] (jsep_transport_controller.cc:1411): 0 Transport 1 state changed. Check if state is complete. [08:10:05:INFO] [000:868] [30487] (jsep_transport_controller.cc:1411): 0 Transport 1 state changed. Check if state is complete. [08:10:05:INFO] [000:868] [34059] (p2p_transport_channel.cc:279): Switching selected connection due to: candidate pair state changed [08:10:05:INFO] [000:869] [34059] (p2p_transport_channel.cc:1869): Channel[0|1|__]: New selected connection: Conn[d28b4400:0:Net[en0:192.168.72.0/22:Wifi:id=1]:ai3VDgRM:1:0:local:udp:192.168.72.60:56954->fnhSDPkz:1:2122260223:local:udp:192.168.72.60:63177|CRWS|S|0|0|9115038255631187454|1] [08:10:05:INFO] [000:869] [34059] (channel.cc:378): Network route was changed. [08:10:05:INFO] [000:869] [34059] (channel.cc:378): Network route was changed. [08:10:05:INFO] [000:869] [34059] (dtls_transport.cc:793): DtlsTransport[0|1|__]: configuring DTLS handshake timeout 50 based on ICE RTT 1 [08:10:05:INFO] [000:869] [34059] (openssl_stream_adapter.cc:777): BeginSSL with peer. [08:10:05:INFO] [000:872] [27919] (webrtc_video_engine.cc:1365): Call stats: 2551663, {send_bw_bps: 0, recv_bw_bps: 0, max_pad_bps: 0, pacer_delay_ms: 0, rtt_ms: -1} [08:10:05:INFO] [000:873] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client enter_early_data [08:10:05:INFO] [000:874] [30487] (dtls_srtp_transport.cc:61): Setting RTCP Transport on 0 transport 0 [08:10:05:INFO] [000:874] [30487] (dtls_srtp_transport.cc:66): Setting RTP Transport on 0 transport d1e2c100 [08:10:05:INFO] [000:874] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_hello_verify_request [08:10:05:INFO] [000:874] [34059] (openssl_adapter.cc:794): SSL_connect:error in TLS client read_hello_verify_request [08:10:05:INFO] [000:874] [30487] (p2p_transport_channel.cc:450): Received remote ICE parameters: ufrag=hhNr, renomination enabled [08:10:05:INFO] [000:874] [34059] (dtls_transport.cc:698): DtlsTransport[0|1|__]: DtlsTransport: Started DTLS handshake [08:10:05:INFO] [000:874] [34059] (srtp_transport.cc:364): The params in SRTP transport are reset. [08:10:05:INFO] [000:874] [30487] (dtls_transport.cc:370): DtlsTransport[0|1|__]: DTLS setup complete. [08:10:05:INFO] [000:874] [34059] (jsep_transport_controller.cc:1411): 0 Transport 1 state changed. Check if state is complete. [08:10:05:INFO] [000:874] [34059] (basic_port_allocator.cc:1159): All candidates gathered for 0:1:0 [08:10:05:INFO] [000:874] [34059] (p2p_transport_channel.cc:861): P2PTransportChannel: 0, component 1 gathering complete [08:10:05:INFO] [000:874] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:874] [30487] (p2p_transport_channel.cc:1493): Channel[0|1|R_]: Have a pingable connection for the first time; starting to ping. [08:10:05:INFO] [000:875] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:875] [27919] (channel.cc:555): Channel enabled [08:10:05:INFO] [000:875] [30487] (p2p_transport_channel.cc:2457): Selecting connection for triggered check: Conn[d20f5200:0:Net[en0:192.168.72.0/22:Wifi:id=1]:1Is9zz7B:1:0:local:udp:192.168.72.60:63177->6DGZ4Ngn:1:1853824767:prflx:udp:192.168.72.60:56954|CR-W|-|0|0|7962116751024340479|-] [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:414 -[ARDAppClient peerConnection:didChangeIceGatheringState:]): ICE gathering state changed: 2 [08:10:05:INFO] [000:875] [30487] (port.cc:1777): Conn[d20f5200:0:Net[en0:192.168.72.0/22:Wifi:id=1]:1Is9zz7B:1:0:local:udp:192.168.72.60:63177->6DGZ4Ngn:1:1853824767:prflx:udp:192.168.72.60:56954|CR-W|-|0|0|7962116751024340479|-]: Sent STUN ping, id=5175594f614e753347326673, use_candidate=0, nomination=0 [08:10:05:INFO] [000:875] [27919] (channel.cc:867): Changing voice state, recv=1 send=0 [08:10:05:INFO] [000:875] [30487] (dtls_transport.cc:546): DtlsTransport[0|1|__]: Packet received before DTLS started. [08:10:05:INFO] [000:875] [30487] (dtls_transport.cc:555): DtlsTransport[0|1|__]: Caching DTLS ClientHello packet until DTLS is started. [08:10:05:INFO] [000:875] [27919] (channel.cc:555): Channel enabled [08:10:05:INFO] [000:876] [30487] (port.cc:1725): Conn[d20f5200:0:Net[en0:192.168.72.0/22:Wifi:id=1]:1Is9zz7B:1:0:local:udp:192.168.72.60:63177->6DGZ4Ngn:1:1853824767:prflx:udp:192.168.72.60:56954|CR-I|-|0|0|7962116751024340479|-]: Received STUN ping response, id=5175594f614e753347326673, code=0, rtt=0, pings_since_last_response=5175594f614e753347326673 [08:10:05:INFO] [000:876] [27919] (channel.cc:1000): Changing video state, send=0 [08:10:05:INFO] [000:876] [30487] (p2p_transport_channel.cc:279): Switching selected connection due to: candidate pair state changed [08:10:05:INFO] [000:876] [50187] (peer_connection.cc:4185): Session: 270370254968405980 Old state: kHaveLocalOffer New state: kStable [08:10:05:INFO] [000:876] [30487] (p2p_transport_channel.cc:1869): Channel[0|1|R_]: New selected connection: Conn[d20f5200:0:Net[en0:192.168.72.0/22:Wifi:id=1]:1Is9zz7B:1:0:local:udp:192.168.72.60:63177->6DGZ4Ngn:1:1853824767:prflx:udp:192.168.72.60:56954|CRWS|S|0|0|7962116751024340479|0] [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:374 -[ARDAppClient peerConnection:didChangeSignalingState:]): Signaling state changed: 0 [08:10:05:INFO] [000:876] [30487] (channel.cc:378): Network route was changed. [08:10:05:INFO] [000:876] [30487] (channel.cc:378): Network route was changed. [08:10:05:INFO] [000:876] [27919] (channel.cc:927): Setting remote voice description [08:10:05:INFO] [000:876] [30487] (dtls_transport.cc:793): DtlsTransport[0|1|__]: configuring DTLS handshake timeout 50 based on ICE RTT 0 [08:10:05:INFO] [000:876] [30487] (openssl_stream_adapter.cc:777): BeginSSL with peer. [08:10:05:INFO] [000:876] [27919] (webrtc_voice_engine.cc:1286): WebRtcVoiceMediaChannel::SetSendParameters: {codecs: [AudioCodec[111:opus:48000:0:2], AudioCodec[103:ISAC:16000:0:1], AudioCodec[104:ISAC:32000:0:1], AudioCodec[9:G722:8000:0:1], AudioCodec[102:ILBC:8000:0:1], AudioCodec[0:PCMU:8000:0:1], AudioCodec[8:PCMA:8000:0:1], AudioCodec[106:CN:32000:0:1], AudioCodec[105:CN:16000:0:1], AudioCodec[13:CN:8000:0:1], AudioCodec[110:telephone-event:48000:0:1], AudioCodec[112:telephone-event:32000:0:1], AudioCodec[113:telephone-event:16000:0:1], AudioCodec[126:telephone-event:8000:0:1]], extensions: [{uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 0, options: AudioOptions {}} [08:10:05:INFO] [000:877] [27919] (audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 2255579466, extmap-allow-mixed: false, extensions: [], c_name: ZUkKncj912NvzTaZ}, rtcp_report_interval_ms: 5000, send_transport: (Transport), media_transport_config: {media_transport: null}, min_bitrate_bps: 32000, max_bitrate_bps: 32000, send_codec_spec: {nack_enabled: false, transport_cc_enabled: true, cng_payload_type: <unset>, payload_type: 111, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {minptime: 10, useinbandfec: 1}}}} [08:10:05:INFO] [000:877] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_client_hello [08:10:05:INFO] [000:877] [30487] (openssl_adapter.cc:794): SSL_accept:error in TLS server read_client_hello [08:10:05:INFO] [000:877] [27919] (audio_encoder_opus.cc:767): Set Opus bitrate to 32000 bps. [08:10:05:INFO] [000:877] [30487] (dtls_transport.cc:698): DtlsTransport[0|1|__]: DtlsTransport: Started DTLS handshake [08:10:05:INFO] [000:877] [27919] (webrtc_voice_engine.cc:1709): Recreate all the receive streams because the send codec has changed. [08:10:05:INFO] [000:877] [30487] (srtp_transport.cc:364): The params in SRTP transport are reset. [08:10:05:INFO] [000:877] [30487] (dtls_transport.cc:704): DtlsTransport[0|1|__]: Handling cached DTLS ClientHello packet. [08:10:05:INFO] [000:877] [27919] (audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 2255579466, extmap-allow-mixed: false, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}], c_name: ZUkKncj912NvzTaZ}, rtcp_report_interval_ms: 5000, send_transport: (Transport), media_transport_config: {media_transport: null}, min_bitrate_bps: 32000, max_bitrate_bps: 32000, send_codec_spec: {nack_enabled: false, transport_cc_enabled: true, cng_payload_type: <unset>, payload_type: 111, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {minptime: 10, useinbandfec: 1}}}} [08:10:05:INFO] [000:877] [27919] (audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 2255579466, extmap-allow-mixed: false, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}], c_name: ZUkKncj912NvzTaZ}, rtcp_report_interval_ms: 5000, send_transport: (Transport), media_transport_config: {media_transport: null}, min_bitrate_bps: 32000, max_bitrate_bps: 32000, send_codec_spec: {nack_enabled: false, transport_cc_enabled: true, cng_payload_type: <unset>, payload_type: 111, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {minptime: 10, useinbandfec: 1}}}} [08:10:05:INFO] [000:877] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server select_certificate [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:2170): WebRtcVoiceMediaChannel::SetMaxSendBitrate. [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:1492): Setting voice channel options: AudioOptions {} [08:10:05:INFO] [000:878] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server select_parameters [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:05:INFO] [000:878] [27919] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:05:INFO] [000:878] [27919] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:05:INFO] [000:878] [27919] (audio_device_module_ios.mm:600): output: 0 [08:10:05:INFO] [000:878] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [000:878] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server send_server_hello [08:10:05:INFO] [000:878] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [000:878] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [000:878] [27919] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:05:INFO] [000:878] [27919] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:05:INFO] [000:878] [27919] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:05:INFO] [000:878] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server send_server_certificate [08:10:05:INFO] [000:878] [27919] (audio_device_module_ios.mm:616): output: 0 [08:10:05:INFO] [000:878] [27919] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:05:INFO] [000:878] [27919] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:05:INFO] [000:878] [27919] (audio_device_module_ios.mm:632): output: 0 [08:10:05:INFO] [000:878] [27919] (apm_helpers.cc:62): NS set to 0 [08:10:05:INFO] [000:878] [27919] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:05:INFO] [000:879] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server send_server_key_exchange [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:05:INFO] [000:881] [27919] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:05:INFO] [000:881] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [000:882] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [000:882] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [000:882] [27919] (webrtc_voice_engine.cc:1510): Set voice channel options. Current options: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:05:INFO] [000:885] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server send_server_hello_done [08:10:05:INFO] [000:898] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_client_certificate [08:10:05:INFO] [000:898] [27919] (webrtc_voice_engine.cc:1856): AddRecvStream: {id:ARDAMSa0;ssrcs:[4124656428];ssrc_groups:;cname:eqki72hWABX2mav5;stream_ids:ARDAMS;} [08:10:05:INFO] [000:898] [30487] (openssl_adapter.cc:794): SSL_accept:error in TLS server read_client_certificate [08:10:05:INFO] [000:898] [30487] (jsep_transport_controller.cc:1411): 0 Transport 1 state changed. Check if state is complete. [08:10:05:INFO] [000:898] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_server_hello [08:10:05:INFO] [000:898] [27919] (neteq_impl.cc:123): NetEq config: sample_rate_hz=16000, enable_post_decode_vad=true, max_packets_in_buffer=200, min_delay_ms=0, enable_fast_accelerate=false, enable_muted_state=true, enable_rtx_handling=false [08:10:05:INFO] [000:898] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_server_certificate [08:10:05:INFO] [000:912] [27919] (audio_coding_module.cc:311): Created [08:10:05:INFO] [000:912] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_certificate_status [08:10:05:INFO] [000:912] [27919] (audio_receive_stream.cc:117): AudioReceiveStream: 4124656428 [08:10:05:INFO] [000:912] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client verify_server_certificate [08:10:05:INFO] [000:912] [27919] (audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 4124656428, local_ssrc: 2255579466, transport_cc: on, nack: {rtp_history_ms: 0}, extensions: [{uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:ssrc-audio-level, id: 1}]}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}, sync_group: ARDAMS} [08:10:05:INFO] [000:912] [34059] (openssl_stream_adapter.cc:1049): Accepted peer certificate. [08:10:05:INFO] [000:919] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_server_key_exchange [08:10:05:INFO] [000:919] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:05:INFO] [000:919] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:05:INFO] [000:919] [27919] (audio_device_module_ios.mm:543): Playing [08:10:05:INFO] [000:919] [27919] (audio_device_module_ios.mm:480): InitPlayout [08:10:05:INFO] [000:919] [27919] (audio_device_module_ios.mm:506): PlayoutIsInitialized [08:10:05:INFO] [000:919] [27919] (audio_device_ios.mm:185): AudioDeviceIOS::InitPlayout [08:10:05:INFO] [000:919] [27919] (audio_device_ios.mm:860): AudioDeviceIOS::InitPlayOrRecord [08:10:05:INFO] [000:920] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_certificate_request [08:10:05:INFO] [000:920] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_server_hello_done [08:10:05:INFO] [000:920] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client send_client_certificate [08:10:05:INFO] [000:920] [27919] (RTCLogging.mm:33): (audio_device_ios.mm:825 ConfigureAudioSession): Configuring audio session. [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (RTCAudioSession.mm:718 -[RTCAudioSession configureWebRTCSession:]): Configuring audio session for WebRTC. [08:10:05:INFO] [000:924] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client send_client_key_exchange [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:120 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set preferred IO buffer duration to: 0.020000 [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (RTCAudioSession.mm:643 -[RTCAudioSession incrementActivationCount]): Incrementing activation count. [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (RTCAudioSession.mm:396 -[RTCAudioSession setActive:error:]): Number of current activations: 2 [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:151 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set input number of channels to: 1 [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (RTCAudioSession+Configuration.mm:166 -[RTCAudioSession(Configuration) setConfiguration:active:shouldSetActive:error:]): Set output number of channels to: 1 [08:10:05:INFO] [000:924] [27919] (RTCLogging.mm:33): (audio_device_ios.mm:836 ConfigureAudioSession): Configured audio session. [08:10:05:INFO] [000:924] [27919] (audio_device_ios.mm:679): AudioDeviceIOS::SetupAudioBuffersForActiveAudioSession [08:10:05:INFO] [000:925] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client send_client_certificate_verify [08:10:05:INFO] [000:948] [27919] (RTCLogging.mm:33): (audio_device_ios.mm:684 SetupAudioBuffersForActiveAudioSession): RTCAudioSession: { [08:10:05:INFO] category: AVAudioSessionCategoryPlayAndRecord [08:10:05:INFO] categoryOptions: 4 [08:10:05:INFO] mode: AVAudioSessionModeVoiceChat [08:10:05:INFO] isActive: 1 [08:10:05:INFO] sampleRate: 48000.00 [08:10:05:INFO] IOBufferDuration: 0.010667 [08:10:05:INFO] outputNumberOfChannels: 2 [08:10:05:INFO] inputNumberOfChannels: 2 [08:10:05:INFO] outputLatency: 0.010000 [08:10:05:INFO] inputLatency: 0.010000 [08:10:05:INFO] outputVolume: 0.600000 [08:10:05:INFO] } [08:10:05:INFO] [000:948] [27919] (audio_device_ios.mm:715): frames per I/O buffer: 512 [08:10:05:INFO] [000:948] [27919] (audio_device_ios.mm:716): bytes per I/O buffer: 1024 [08:10:05:INFO] [000:948] [27919] (audio_device_ios.mm:663): AudioDeviceIOS::UpdateAudioDevicebuffer [08:10:05:INFO] [000:948] [27919] (audio_device_buffer.cc:186): SetPlayoutSampleRate(48000) [08:10:05:INFO] [000:948] [27919] (audio_device_buffer.cc:206): SetPlayoutChannels(1) [08:10:05:INFO] [000:948] [27919] (audio_device_buffer.cc:180): SetRecordingSampleRate(48000) [08:10:05:INFO] [000:951] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client send_client_finished [08:10:05:INFO] [000:951] [27919] (audio_device_buffer.cc:200): SetRecordingChannels(1) [08:10:05:INFO] [000:951] [27919] (fine_audio_buffer.cc:32): FineAudioBuffer [08:10:05:INFO] [000:951] [27919] (fine_audio_buffer.cc:34): playout_samples_per_channel_10ms: 480 [08:10:05:INFO] [000:952] [27919] (fine_audio_buffer.cc:36): playout_channels: 1 [08:10:05:INFO] [000:952] [27919] (fine_audio_buffer.cc:39): record_samples_per_channel_10ms: 480 [08:10:05:INFO] [000:952] [27919] (fine_audio_buffer.cc:41): record_channels: 1 [08:10:05:INFO] [000:952] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:196 Initialize): Initializing audio unit with sample rate: 48000.000000 [08:10:05:INFO] [000:952] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:43 LogStreamDescription): AudioStreamBasicDescription: { [08:10:05:INFO] mSampleRate: 48000.00 [08:10:05:INFO] formatIDString: lpcm [08:10:05:INFO] mFormatFlags: 0xC [08:10:05:INFO] mBytesPerPacket: 2 [08:10:05:INFO] mFramesPerPacket: 1 [08:10:05:INFO] mBytesPerFrame: 2 [08:10:05:INFO] mChannelsPerFrame: 1 [08:10:05:INFO] mBitsPerChannel: 16 [08:10:05:INFO] mReserved: 28672 [08:10:05:INFO] } [08:10:05:INFO] [000:952] [30487] (openssl_stream_adapter.cc:940): DTLS timeout expired [08:10:05:INFO] [000:952] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client finish_flight [08:10:05:INFO] [000:952] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:250 Initialize): Voice Processing I/O unit is now initialized. [08:10:05:INFO] [000:957] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:71 GetAGCState): VPIO unit AGC: 0 [08:10:05:INFO] [000:957] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:264 Initialize): Failed to get AGC state (1st attempt). Error=-66631. [08:10:05:INFO] [000:957] [30487] (openssl_adapter.cc:794): SSL_accept:error in TLS server read_client_certificate [08:10:05:INFO] [000:957] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:302 Initialize): WebRTC.Audio.BuiltInAGCWasEnabledByDefault: 0 [08:10:05:INFO] [000:957] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:308 Initialize): WebRTC.Audio.BuiltInAGCIsEnabled: 0 [08:10:05:INFO] [000:957] [27919] (audio_device_module_ios.mm:486): output: 0 [08:10:05:INFO] [000:957] [27919] (audio_device_module_ios.mm:518): StartPlayout [08:10:05:INFO] [000:957] [27919] (audio_device_module_ios.mm:543): Playing [08:10:05:INFO] [000:957] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_session_ticket [08:10:05:INFO] [000:957] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server verify_client_certificate [08:10:05:INFO] [000:974] [34059] (openssl_adapter.cc:794): SSL_connect:error in TLS client read_session_ticket [08:10:05:INFO] [000:974] [27919] (audio_device_buffer.cc:98): StartPlayout [08:10:05:INFO] [000:974] [30487] (openssl_stream_adapter.cc:1049): Accepted peer certificate. [08:10:05:INFO] [000:974] [27919] (audio_device_ios.mm:227): AudioDeviceIOS::StartPlayout [08:10:05:INFO] [000:974] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_client_key_exchange [08:10:05:INFO] [000:974] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:316 Start): Starting audio unit. [08:10:05:INFO] [000:975] [34059] (openssl_adapter.cc:794): SSL_connect:error in TLS client read_session_ticket [08:10:05:INFO] [000:975] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_client_certificate_verify [08:10:05:INFO] [000:975] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_change_cipher_spec [08:10:05:INFO] [000:975] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server process_change_cipher_spec [08:10:05:INFO] [000:976] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_next_proto [08:10:05:INFO] [000:976] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_channel_id [08:10:05:INFO] [000:976] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server read_client_finished [08:10:05:INFO] [000:976] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server send_server_finished [08:10:05:INFO] [000:976] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server finish_server_handshake [08:10:05:INFO] [000:976] [30487] (openssl_adapter.cc:784): SSL_accept:TLS server done [08:10:05:INFO] [000:976] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client process_change_cipher_spec [08:10:05:INFO] [000:976] [30487] (dtls_transport.cc:630): DtlsTransport[0|1|__]: DTLS handshake complete. [08:10:05:INFO] [000:976] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client read_server_finished [08:10:05:INFO] [000:976] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client finish_client_handshake [08:10:05:INFO] [000:976] [34059] (openssl_adapter.cc:784): SSL_connect:TLS client done [08:10:05:INFO] [000:976] [34059] (dtls_transport.cc:630): DtlsTransport[0|1|__]: DTLS handshake complete. [08:10:05:INFO] [000:976] [30487] (jsep_transport_controller.cc:1348): Transport 0 writability changed to 1. [08:10:05:INFO] [000:977] [30487] (dtls_srtp_transport.cc:219): Extracting keys from transport: 0 [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:409 -[ARDAppClient peerConnection:didChangeConnectionState:]): ICE+DTLS state changed: 2 [08:10:05:INFO] [000:977] [34059] (jsep_transport_controller.cc:1348): Transport 0 writability changed to 1. [08:10:05:INFO] [000:977] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:05:INFO] [000:977] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:05:INFO] [000:977] [50947] (peer_connection.cc:6061): Changing to ICE connected state because all transports are writable. [08:10:05:INFO] [000:977] [34059] (dtls_srtp_transport.cc:219): Extracting keys from transport: 0 [08:10:05:INFO] [000:977] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:05:INFO] [000:977] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:05:INFO] [000:977] [50947] (peer_connection.cc:4117): Changing IceConnectionState 1 => 2 [08:10:05:INFO] [000:977] [30215] (rtp_transport_controller_send.cc:462): Creating fallback congestion controller [08:10:05:INFO] (RTCLogging.mm:33): (ARDAppClient.m:401 -[ARDAppClient peerConnection:didChangeIceConnectionState:]): ICE state changed: 2 [08:10:05:INFO] [000:978] [30215] (alr_experiment.cc:78): Using ALR experiment settings: pacing factor: 1, max pacer queue length: 2875, ALR start bandwidth usage percent: 80, ALR end budget level percent: 40, ALR end budget level percent: -60, ALR experiment group ID: 3 [08:10:05:INFO] [000:978] [30215] (trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20 [08:10:05:INFO] [000:978] [30215] (aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85 [08:10:05:INFO] [000:979] [30215] (delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps [08:10:05:INFO] [000:979] [30215] (probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000 [08:10:05:INFO] [000:979] [30215] (bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5) [08:10:05:INFO] [000:979] [30215] (bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5) [08:10:05:INFO] [000:979] [30215] (bitrate_allocator.cc:117): Current BWE 300000 [08:10:05:INFO] [000:982] [30487] (srtp_transport.cc:309): SRTP activated with negotiated parameters: send cipher_suite 1 recv cipher_suite 1 [08:10:05:INFO] [000:982] [34059] (srtp_transport.cc:309): SRTP activated with negotiated parameters: send cipher_suite 1 recv cipher_suite 1 [08:10:05:INFO] [000:983] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:320 Start): Failed to start audio unit. Error=-66637 [08:10:05:INFO] [001:003] [30487] (channel.cc:585): Channel writable (0) for the first time [08:10:05:INFO] [001:003] [34059] (channel.cc:585): Channel writable (0) for the first time [08:10:05:INFO] [001:003] [27919] (RTCLogging.mm:33): (audio_device_ios.mm:237 StartPlayout): StartPlayout failed to start audio unit. [08:10:05:INFO] [001:003] [30487] (channel.cc:585): Channel writable (1) for the first time [08:10:05:INFO] [001:003] [34059] (channel.cc:585): Channel writable (1) for the first time [08:10:05:INFO] [001:003] [27919] (audio_device_module_ios.mm:525): output: -1 [08:10:05:INFO] [001:003] [27919] (channel.cc:721): Add remote ssrc: 4124656428 [08:10:05:INFO] [001:004] [50691] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:05:INFO] [001:004] [27919] (webrtc_voice_engine.cc:309): WebRtcVoiceEngine::ApplyOptions: AudioOptions {audio_jitter_buffer_max_packets: 200, audio_jitter_buffer_fast_accelerate: 0, audio_jitter_buffer_min_delay_ms: 0, audio_jitter_buffer_enable_rtx_handling: 0, } [08:10:05:INFO] [001:012] [50691] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:05:INFO] [001:012] [27919] (webrtc_voice_engine.cc:330): Always disable AEC on iOS. Use built-in instead. [08:10:05:INFO] [001:012] [50691] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:05:INFO] [001:012] [27919] (webrtc_voice_engine.cc:357): Always disable NS on iOS. Use built-in instead. [08:10:05:INFO] [001:012] [50691] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:05:INFO] [001:012] [27919] (webrtc_voice_engine.cc:368): Always disable AGC on iOS. Use built-in instead. [08:10:05:INFO] [001:012] [50691] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:05:INFO] [001:012] [27919] (audio_device_module_ios.mm:597): BuiltInAECIsAvailable [08:10:05:INFO] [001:012] [50691] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:05:INFO] [001:013] [27919] (audio_device_generic.cc:17): virtual bool webrtc::AudioDeviceGeneric::BuiltInAECIsAvailable() const: Not supported on this platform [08:10:05:INFO] [001:013] [50691] (audio_device_module_ios.mm:600): output: 0 [08:10:05:INFO] [001:013] [27919] (audio_device_module_ios.mm:600): output: 0 [08:10:05:INFO] [001:013] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [001:013] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [001:013] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [001:013] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [001:013] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [001:013] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [001:013] [27919] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:05:INFO] [001:013] [50691] (apm_helpers.cc:48): Echo control set to 0 with mode 0 [08:10:05:INFO] [001:013] [27919] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:05:INFO] [001:013] [50691] (audio_device_module_ios.mm:613): BuiltInAGCIsAvailable [08:10:05:INFO] [001:013] [27919] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:05:INFO] [001:013] [50691] (audio_device_generic.cc:27): virtual bool webrtc::AudioDeviceGeneric::BuiltInAGCIsAvailable() const: Not supported on this platform [08:10:05:INFO] [001:013] [27919] (audio_device_module_ios.mm:616): output: 0 [08:10:05:INFO] [001:013] [50691] (audio_device_module_ios.mm:616): output: 0 [08:10:05:INFO] [001:013] [27919] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:05:INFO] [001:013] [50691] (audio_device_module_ios.mm:629): BuiltInNSIsAvailable [08:10:05:INFO] [001:013] [27919] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:05:INFO] [001:013] [50691] (audio_device_generic.cc:37): virtual bool webrtc::AudioDeviceGeneric::BuiltInNSIsAvailable() const: Not supported on this platform [08:10:05:INFO] [001:013] [27919] (audio_device_module_ios.mm:632): output: 0 [08:10:05:INFO] [001:064] [27919] (apm_helpers.cc:62): NS set to 0 [08:10:05:INFO] [001:064] [50691] (audio_device_module_ios.mm:632): output: 0 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:05:INFO] [001:064] [50691] (apm_helpers.cc:62): NS set to 0 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:452): NetEq capacity is 200 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:458): NetEq fast mode? 0 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:464): NetEq minimum delay is 0 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:470): NetEq handle reordered packets? 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:481): Delay agnostic aec is enabled? 0 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:491): Extended filter aec is enabled? 0 [08:10:05:INFO] [001:064] [27919] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:05:INFO] [001:064] [50691] (webrtc_voice_engine.cc:501): Experimental ns is enabled? 0 [08:10:05:INFO] [001:072] [50691] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:05:INFO] [001:072] [27919] (webrtc_voice_engine.cc:511): Setting AGC to 0 [08:10:05:INFO] [001:072] [50691] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:05:INFO] [001:072] [27919] (webrtc_voice_engine.cc:533): Typing detection is enabled? 0 [08:10:05:INFO] [001:073] [50691] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [001:073] [27919] (audio_processing_impl.cc:699): Highpass filter activated: 1 [08:10:05:INFO] [001:073] [27919] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [001:073] [50691] (audio_processing_impl.cc:717): Gain Controller 2 activated: 0 [08:10:05:INFO] [001:073] [27919] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [001:073] [50691] (audio_processing_impl.cc:719): Pre-amplifier activated: 0 [08:10:05:INFO] [001:073] [27919] (audio_device_module_ios.mm:512): RecordingIsInitialized [08:10:05:INFO] [001:073] [50691] (audio_device_module_ios.mm:512): RecordingIsInitialized [08:10:05:INFO] [001:073] [20483] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 32000bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 32000bps [08:10:05:INFO] [001:073] [21251] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 32000bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 32000bps [08:10:05:INFO] [001:083] [50691] (audio_device_module_ios.mm:574): Recording [08:10:05:INFO] [001:083] [27919] (audio_device_module_ios.mm:574): Recording [08:10:05:INFO] [001:083] [50691] (audio_device_module_ios.mm:493): InitRecording [08:10:05:INFO] [001:083] [27919] (audio_device_module_ios.mm:493): InitRecording [08:10:05:INFO] [001:083] [50691] (audio_device_module_ios.mm:512): RecordingIsInitialized [08:10:05:INFO] [001:083] [27919] (audio_device_module_ios.mm:512): RecordingIsInitialized [08:10:05:INFO] [001:083] [50691] (audio_device_module_ios.mm:549): StartRecording [08:10:05:INFO] [001:083] [27919] (audio_device_module_ios.mm:549): StartRecording [08:10:05:INFO] [001:083] [50691] (audio_device_module_ios.mm:574): Recording [08:10:05:INFO] [001:083] [27919] (audio_device_module_ios.mm:574): Recording [08:10:05:INFO] [001:083] [50691] (audio_device_buffer.cc:117): StartRecording [08:10:05:INFO] [001:083] [27919] (audio_device_buffer.cc:117): StartRecording [08:10:05:INFO] [001:083] [50691] (audio_device_ios.mm:280): AudioDeviceIOS::StartRecording [08:10:05:INFO] [001:096] [27919] (audio_device_ios.mm:280): AudioDeviceIOS::StartRecording [08:10:05:INFO] [001:096] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:316 Start): Starting audio unit. [08:10:05:INFO] [001:096] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:316 Start): Starting audio unit. [08:10:05:INFO] [001:100] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:320 Start): Failed to start audio unit. Error=-66637 [08:10:05:INFO] [001:100] [50691] (RTCLogging.mm:33): (audio_device_ios.mm:290 StartRecording): StartRecording failed to start audio unit. [08:10:05:INFO] [001:100] [50691] (audio_device_module_ios.mm:556): output: -1 [08:10:05:INFO] [001:100] [50691] (channel.cc:867): Changing voice state, recv=1 send=1 [08:10:05:INFO] [001:100] [50691] (message_queue.cc:517): Message took 97ms to dispatch. Posted from: UpdateMediaSendRecvState@../../pc/channel.cc:832 [08:10:05:INFO] [001:100] [50691] (video_send_stream.cc:133): VideoSendStream::UpdateActiveSimulcastLayers [08:10:05:INFO] [001:100] [30215] (video_send_stream_impl.cc:363): VideoSendStream::UpdateActiveSimulcastLayers [08:10:05:INFO] [001:101] [30215] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 32000bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 10032000bps [08:10:05:INFO] [001:101] [21251] (video_stream_encoder.cc:1604): Video suspend state changed to: not suspended [08:10:05:INFO] [001:102] [50691] (channel.cc:1000): Changing video state, send=1 [08:10:05:INFO] [001:111] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:320 Start): Failed to start audio unit. Error=-66637 [08:10:05:INFO] [001:111] [27919] (RTCLogging.mm:33): (audio_device_ios.mm:290 StartRecording): StartRecording failed to start audio unit. [08:10:05:INFO] [001:111] [27919] (audio_device_module_ios.mm:556): output: -1 [08:10:05:INFO] [001:111] [27919] (channel.cc:867): Changing voice state, recv=1 send=1 [08:10:05:INFO] [001:111] [27919] (message_queue.cc:517): Message took 235ms to dispatch. Posted from: SetRemoteContent@../../pc/channel.cc:304 [08:10:05:INFO] [001:111] [27919] (channel.cc:1065): Setting remote video description [08:10:05:INFO] [001:112] [27919] (webrtc_video_engine.cc:690): SetSendParameters: {codecs: [VideoCodec[96:H264], VideoCodec[97:rtx], VideoCodec[98:H264], VideoCodec[99:rtx], VideoCodec[100:VP8], VideoCodec[101:rtx], VideoCodec[127:VP9], VideoCodec[123:rtx], VideoCodec[125:red], VideoCodec[122:rtx], VideoCodec[124:ulpfec]], conference_mode: no, extensions: [{uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}], extmap-allow-mixed: false, max_bandwidth_bps: -1, mid: 1} [08:10:05:INFO] [001:112] [27919] (webrtc_video_engine.cc:699): Using codec: VideoCodec[96:H264] [08:10:05:INFO] [001:112] [27919] (webrtc_video_engine.cc:1905): RecreateWebRtcStream (send) because of SetCodec. [08:10:05:INFO] [001:113] [27919] (balanced_degradation_settings.cc:31): Unsupported size, value ignored. [08:10:05:INFO] [001:113] [27919] (alr_experiment.cc:78): Using ALR experiment settings: pacing factor: 1, max pacer queue length: 2875, ALR start bandwidth usage percent: 80, ALR end budget level percent: 40, ALR end budget level percent: -60, ALR experiment group ID: 3 [08:10:05:INFO] [001:113] [21251] (rtp_video_sender.cc:510): Transmitting payload type without picture ID using NACK+ULPFEC is a waste of bandwidth since ULPFEC packets also have to be retransmitted. Disabling ULPFEC. [08:10:05:INFO] [001:113] [21251] (video_send_stream_impl.cc:250): VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [617989588], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 124, red_payload_type: 125, red_rtx_payload_type: 122}, payload_name: H264, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [1547243348], payload_type: 97}, c_name: ZUkKncj912NvzTaZ}, rtcp_report_interval_ms: 1000, send_transport: (Transport), media_transport: nullptr, render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [08:10:07:INFO] [001:143] [21251] (video_send_stream_impl.cc:278): ERROR: Initial encoder max bitrate = -1 which is <= 0! [08:10:07:INFO] [001:143] [21251] (video_send_stream_impl.cc:280): Using default encoder max bitrate = 10 Mbps [08:10:07:INFO] [001:143] [21251] (video_stream_encoder.cc:609): ConfigureEncoder requested. [08:10:07:INFO] [001:143] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:07:INFO] [001:143] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:07:INFO] [001:143] [27919] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:07:INFO] [001:143] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:143] [27919] (webrtc_video_engine.cc:767): SetFeedbackOptions on all the receive streams because the send codec or RTCP mode has changed. [08:10:07:INFO] [001:143] [27919] (webrtc_video_engine.cc:1190): AddRecvStream: {id:ARDAMSv0;ssrcs:[2623606793,3720308880];ssrc_groups:{semantics:FID;ssrcs:[2623606793,3720308880]};cname:eqki72hWABX2mav5;stream_ids:ARDAMS;} [08:10:07:INFO] [001:145] [27919] (video_receive_stream.cc:220): VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 127, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 2623606793, local_ssrc: 617989588, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 124, red_type: 125, rtx_ssrc: 3720308880, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 122 (pt) -> 125 (apt), 123 (pt) -> 127 (apt), }, raw_payload_types: {}, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}]}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:07:INFO] [001:165] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:07:INFO] [001:165] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:07:INFO] [001:165] [27919] (channel.cc:721): Add remote ssrc: 2623606793 [08:10:07:INFO] [001:165] [27919] (video_send_stream.cc:133): VideoSendStream::UpdateActiveSimulcastLayers [08:10:07:INFO] [001:166] [21251] (video_send_stream_impl.cc:363): VideoSendStream::UpdateActiveSimulcastLayers [08:10:07:INFO] [001:166] [21251] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 32000bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 10032000bps [08:10:07:INFO] [001:166] [27919] (channel.cc:1000): Changing video state, send=1 [08:10:07:INFO] [001:166] [27919] (message_queue.cc:517): Message took 55ms to dispatch. Posted from: SetRemoteContent@../../pc/channel.cc:304 [08:10:07:INFO] [001:166] [50187] (peer_connection.cc:4117): Changing IceConnectionState 0 => 1 [08:10:07:INFO] [001:166] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:166] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:401 -[ARDAppClient peerConnection:didChangeIceConnectionState:]): ICE state changed: 1 [08:10:07:INFO] [001:166] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:166] [50187] (peer_connection.cc:5537): Non-rejected SCTP m= section is needed to get the SSL Role of the SCTP transport. [08:10:07:INFO] [001:166] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:166] [30215] (rtp_transport_controller_send.cc:462): Creating fallback congestion controller [08:10:07:INFO] [001:166] [50187] (peer_connection.cc:2727): Processing the MSIDs for MID=0 (streams=[ARDAMS]). [08:10:07:INFO] [001:166] [50187] (peer_connection.cc:2738): Processing the addition of a remote track for MID=0. [08:10:07:INFO] [001:166] [27919] (channel.cc:867): Changing voice state, recv=1 send=1 [08:10:07:INFO] [001:166] [30215] (alr_experiment.cc:78): Using ALR experiment settings: pacing factor: 1, max pacer queue length: 2875, ALR start bandwidth usage percent: 80, ALR end budget level percent: 40, ALR end budget level percent: -60, ALR experiment group ID: 3 [08:10:07:INFO] [001:166] [50187] (rtp_transceiver.cc:174): Changing transceiver (MID=0) current direction from <not set> to kSendRecv. [08:10:07:INFO] [001:167] [30215] (trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20 [08:10:07:INFO] [001:167] [27919] (video_send_stream.cc:133): VideoSendStream::UpdateActiveSimulcastLayers [08:10:07:INFO] [001:167] [30215] (aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85 [08:10:07:INFO] [001:167] [30215] (delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps [08:10:07:INFO] [001:167] [30215] (probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000 [08:10:07:INFO] [001:167] [30215] (bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5) [08:10:07:INFO] [001:167] [30215] (bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5) [08:10:07:INFO] [001:167] [30215] (bitrate_allocator.cc:117): Current BWE 300000 [08:10:07:INFO] [001:167] [30215] (video_send_stream_impl.cc:363): VideoSendStream::UpdateActiveSimulcastLayers [08:10:07:INFO] [001:167] [21251] (video_stream_encoder.cc:1604): Video suspend state changed to: not suspended [08:10:07:INFO] [001:167] [27919] (channel.cc:1000): Changing video state, send=1 [08:10:07:INFO] [001:168] [27919] (webrtc_voice_engine.cc:1969): SetOutputVolume() to 1 for recv stream with ssrc 4124656428 [08:10:07:INFO] [001:226] [27919] (message_queue.cc:517): Message took 58ms to dispatch. Posted from: SetOutputVolume@../../pc/audio_rtp_receiver.cc:77 [08:10:07:INFO] [001:227] [50187] (peer_connection.cc:2727): Processing the MSIDs for MID=1 (streams=[ARDAMS]). [08:10:07:INFO] [001:227] [50187] (peer_connection.cc:2738): Processing the addition of a remote track for MID=1. [08:10:07:INFO] [001:227] [50187] (rtp_transceiver.cc:174): Changing transceiver (MID=1) current direction from <not set> to kSendRecv. [08:10:07:INFO] [001:227] [27919] (webrtc_video_engine.cc:1320): SetSink: ssrc:2623606793 (ptr) [08:10:07:INFO] [001:227] [50187] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f08630): created DTMF sender: RTCDtmfSender { [08:10:07:INFO] remainingTones: [08:10:07:INFO] duration: 0.100000 sec [08:10:07:INFO] interToneGap: 0.050000 sec [08:10:07:INFO] } [08:10:07:INFO] [001:227] [50187] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d18bc0): created sender: RTCRtpSender { [08:10:07:INFO] senderId: ARDAMSa0 [08:10:07:INFO] } [08:10:07:INFO] [001:227] [50187] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x60000033aa60): created receiver: RTCRtpReceiver { [08:10:07:INFO] receiverId: cb180711-83a1-4e85-8e6a-33c93fda135b [08:10:07:INFO] } [08:10:07:INFO] [001:235] [50187] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x60000033a970): created transceiver: RTCRtpTransceiver { [08:10:07:INFO] sender: RTCRtpSender { [08:10:07:INFO] senderId: ARDAMSa0 [08:10:07:INFO] } [08:10:07:INFO] receiver: RTCRtpReceiver { [08:10:07:INFO] receiverId: cb180711-83a1-4e85-8e6a-33c93fda135b [08:10:07:INFO] } [08:10:07:INFO] } [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:387 -[ARDAppClient peerConnection:didStartReceivingOnTransceiver:]): Now receiving audio on track cb180711-83a1-4e85-8e6a-33c93fda135b. [08:10:07:INFO] [001:236] [50187] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:07:INFO] [001:236] [50187] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d1bea0): created sender: RTCRtpSender { [08:10:07:INFO] senderId: ARDAMSv0 [08:10:07:INFO] } [08:10:07:INFO] [001:236] [50187] (RTCLogging.mm:33): (RTCRtpReceiver.mm:120 -[RTCRtpReceiver initWithFactory:nativeRtpReceiver:]): RTCRtpReceiver(0x60000033a790): created receiver: RTCRtpReceiver { [08:10:07:INFO] receiverId: 9598fc53-5256-4229-8c50-0b9cd2e4ebe6 [08:10:07:INFO] } [08:10:07:INFO] [001:236] [50187] (RTCLogging.mm:33): (RTCRtpTransceiver.mm:136 -[RTCRtpTransceiver initWithFactory:nativeRtpTransceiver:]): RTCRtpTransceiver(0x60000033a970): created transceiver: RTCRtpTransceiver { [08:10:07:INFO] sender: RTCRtpSender { [08:10:07:INFO] senderId: ARDAMSv0 [08:10:07:INFO] } [08:10:07:INFO] receiver: RTCRtpReceiver { [08:10:07:INFO] receiverId: 9598fc53-5256-4229-8c50-0b9cd2e4ebe6 [08:10:07:INFO] } [08:10:07:INFO] } [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:387 -[ARDAppClient peerConnection:didStartReceivingOnTransceiver:]): Now receiving video on track 9598fc53-5256-4229-8c50-0b9cd2e4ebe6. [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:381 -[ARDAppClient peerConnection:didAddStream:]): Stream with 1 video tracks and 1 audio tracks was added. [08:10:07:INFO] [001:257] [50187] (message_queue.cc:517): Message took 389ms to dispatch. Posted from: SetRemoteDescription@../../api/peer_connection_proxy.h:104 [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:409 -[ARDAppClient peerConnection:didChangeConnectionState:]): ICE+DTLS state changed: 1 [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:409 -[ARDAppClient peerConnection:didChangeConnectionState:]): ICE+DTLS state changed: 2 [08:10:07:INFO] [001:257] [9987] (RTCLogging.mm:33): (RTCDtmfSender.mm:70 -[RTCDtmfSender initWithNativeDtmfSender:]): RTCDtmfSender(0x600000f0e7e0): created DTMF sender: RTCDtmfSender { [08:10:07:INFO] remainingTones: [08:10:07:INFO] duration: 0.100000 sec [08:10:07:INFO] interToneGap: 0.050000 sec [08:10:07:INFO] } [08:10:07:INFO] [001:260] [50187] (peer_connection.cc:6067): Changing to ICE completed state because all transports are complete. [08:10:07:INFO] [001:260] [50187] (peer_connection.cc:4117): Changing IceConnectionState 1 => 2 [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:401 -[ARDAppClient peerConnection:didChangeIceConnectionState:]): ICE state changed: 2 [08:10:07:INFO] [001:260] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d22ee0): created sender: RTCRtpSender { [08:10:07:INFO] senderId: ARDAMSa0 [08:10:07:INFO] } [08:10:07:INFO] [001:260] [50187] (peer_connection.cc:4117): Changing IceConnectionState 2 => 3 [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:401 -[ARDAppClient peerConnection:didChangeIceConnectionState:]): ICE state changed: 3 [08:10:07:INFO] [001:260] [50947] (rtp_sender.cc:590): Tried to get DTMF sender from video sender. [08:10:07:INFO] [001:265] [9987] (RTCLogging.mm:33): (RTCRtpSender.mm:107 -[RTCRtpSender initWithFactory:nativeRtpSender:]): RTCRtpSender(0x600000d22ea0): created sender: RTCRtpSender { [08:10:07:INFO] senderId: ARDAMSv0 [08:10:07:INFO] } [08:10:07:INFO] [001:267] [34059] (jsep_transport_controller.cc:351): Not adding candidate because the JsepTransport doesn't exist. Ignore it. [08:10:07:INFO] [001:269] [50691] (rtc_event_log_impl.cc:141): Stopping WebRTC event log. [08:10:07:INFO] [001:269] [50691] (rtc_event_log_impl.cc:153): WebRTC event log successfully stopped. [08:10:07:INFO] [001:270] [50691] (webrtc_video_engine.cc:2256): VideoSendStream stats: 2552061, {input_fps: 0, encode_fps: 0, encode_ms: 0, encode_usage_perc: 0, target_bps: 268000, media_bps: 0, suspended: false, bw_adapted_res: false, cpu_adapted_res: false, bw_adapted_fps: false, cpu_adapted_fps: false, #cpu_adaptations: 0, #quality_adaptations: 0} {ssrc: 2623606793, width: 0, height: 0, key: 0, delta: 0, total_bps: 0, retransmit_bps: 0, avg_delay_ms: 0, max_delay_ms: 0, cum_loss: 0, max_ext_seq: 0, nack: 0, fir: 0, pli: 0} [08:10:07:INFO] [001:270] [50691] (webrtc_video_engine.cc:2762): VideoReceiveStream stats: 2552061, {ssrc: 617989588, total_bps: 0, width: 0, height: 0, key: 0, delta: 0, network_fps: 0, decode_fps: 0, render_fps: 0, decode_ms: 0, max_decode_ms: 0, first_frame_received_to_decoded_ms: -1, cur_delay_ms: 0, targ_delay_ms: 0, jb_delay_ms: 0, min_playout_delay_ms: 0, sync_offset_ms: 2147483647, cum_loss: 0, max_ext_seq: 0, nack: 0, fir: 0, pli: 0} [08:10:07:INFO] [001:272] [50691] (webrtc_video_engine.cc:1365): Call stats: 2552061, {send_bw_bps: 300000, recv_bw_bps: 0, max_pad_bps: 0, pacer_delay_ms: 0, rtt_ms: -1} [08:10:07:INFO] [001:272] [50947] (peer_connection.cc:4185): Session: 7061599334522676903 Old state: kStable New state: kClosed [08:10:07:INFO] [001:272] [30215] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 0bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 10000000bps [08:10:07:INFO] [001:272] [50691] (audio_device_module_ios.mm:563): StopRecording [08:10:07:INFO] [001:272] [50691] (audio_device_ios.mm:300): AudioDeviceIOS::StopRecording [08:10:07:INFO] [001:272] [50691] (audio_device_buffer.cc:153): StopRecording [08:10:07:INFO] [001:273] [50691] (audio_device_buffer.cc:176): total recording time: 190 [08:10:07:INFO] [001:273] [50691] (audio_device_module_ios.mm:567): output: 0 [08:10:07:INFO] [001:273] [50691] (webrtc_voice_engine.cc:1969): SetOutputVolume() to 0 for recv stream with ssrc 2255579466 [08:10:07:INFO] [001:273] [50691] (webrtc_video_engine.cc:1040): SetVideoSend (ssrc= 2623606793, options: nullptr, source = nullptr) [08:10:07:INFO] [001:273] [50691] (webrtc_video_engine.cc:1320): SetSink: ssrc:617989588 nullptr [08:10:07:INFO] [001:273] [50691] (channel.cc:565): Channel disabled [08:10:07:INFO] [001:273] [50691] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:07:INFO] [001:273] [50691] (channel.cc:1000): Changing video state, send=0 [08:10:07:INFO] [001:273] [30215] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:273] [30215] (rtp_rtcp_impl.cc:360): Failed to send RTCP BYE [08:10:07:INFO] [001:273] [30215] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 0bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 0bps [08:10:07:INFO] [001:273] [50691] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:07:INFO] [001:279] [30215] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:279] [21251] (video_stream_encoder.cc:1604): Video suspend state changed to: suspended [08:10:07:INFO] [001:279] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:279] [21251] (video_send_stream_impl.cc:338): ~VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [2623606793], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 124, red_payload_type: 125, red_rtx_payload_type: 122}, payload_name: H264, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [3720308880], payload_type: 97}, c_name: eqki72hWABX2mav5}, rtcp_report_interval_ms: 1000, send_transport: (Transport), media_transport: nullptr, render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [08:10:07:INFO] [001:279] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:279] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:280] [50691] (send_statistics_proxy.cc:655): Frames encoded 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Capturer 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.EncoderQueue 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Encoder 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Ratelimiter 0 [08:10:07:INFO] [001:290] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:290] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:290] [50691] (video_receive_stream.cc:287): ~VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 127, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 617989588, local_ssrc: 2623606793, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 124, red_type: 125, rtx_ssrc: 1547243348, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 122 (pt) -> 125 (apt), 123 (pt) -> 127 (apt), }, raw_payload_types: {}, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}]}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:07:INFO] [001:297] [50691] (video_render_frames.cc:47): WebRTC.Video.DroppedFrames.RenderQueue 0 [08:10:07:INFO] [001:298] [50691] (receive_statistics_proxy.cc:491): Frames decoded 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Receiver 0 [08:10:07:INFO] [08:10:07:INFO] [001:298] [50691] (channel.cc:163): Destroyed channel: 1 [08:10:07:INFO] [001:298] [50691] (channel.cc:565): Channel disabled [08:10:07:INFO] [001:298] [50691] (audio_device_module_ios.mm:532): StopPlayout [08:10:07:INFO] [001:298] [50691] (audio_device_ios.mm:249): AudioDeviceIOS::StopPlayout [08:10:07:INFO] [001:298] [50691] (audio_device_buffer.cc:139): StopPlayout [08:10:07:INFO] [001:298] [50691] (audio_device_buffer.cc:145): total playout time: 464 [08:10:07:INFO] [001:298] [50691] (audio_device_module_ios.mm:536): output: 0 [08:10:07:INFO] [001:298] [50691] (channel.cc:867): Changing voice state, recv=0 send=0 [08:10:07:INFO] [001:298] [50691] (webrtc_voice_engine.cc:1830): RemoveSendStream: 4124656428 [08:10:07:INFO] [001:298] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:298] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:298] [50691] (audio_send_stream.cc:179): ~AudioSendStream: 4124656428 [08:10:07:INFO] [001:301] [50691] (webrtc_voice_engine.cc:1907): RemoveRecvStream: 2255579466 [08:10:07:INFO] [001:301] [50691] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:07:INFO] [001:301] [50691] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:07:INFO] [001:301] [50691] (audio_receive_stream.cc:140): ~AudioReceiveStream: 2255579466 [08:10:07:INFO] [001:301] [47879] (paced_sender.cc:106): PacedSender paused. [08:10:07:INFO] [001:301] [47879] (control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps. [08:10:07:INFO] [001:301] [50691] (channel.cc:163): Destroyed channel: 0 [08:10:07:INFO] [001:302] [34059] (openssl_stream_adapter.cc:896): Cleanup [08:10:07:INFO] [001:302] [34059] (openssl_adapter.cc:787): SSL3 alert write:warning:close notify [08:10:07:INFO] [001:302] [30487] (openssl_adapter.cc:787): SSL3 alert read:warning:close notify [08:10:07:INFO] [001:302] [30487] (openssl_stream_adapter.cc:896): Cleanup [08:10:07:INFO] [001:302] [30487] (openssl_adapter.cc:787): SSL3 alert write:warning:close notify [08:10:07:INFO] [001:302] [30487] (dtls_transport.cc:651): DtlsTransport[0|1|_W]: DTLS transport closed [08:10:07:INFO] [001:302] [30487] (jsep_transport_controller.cc:1348): Transport 0 writability changed to 0. [08:10:07:INFO] [001:302] [50691] (paced_sender.cc:421): ProcessThreadAttached 0x0 [08:10:07:INFO] [001:305] [50691] (paced_sender.cc:421): ProcessThreadAttached 0x0 [08:10:07:INFO] [001:305] [50187] (peer_connection.cc:4117): Changing IceConnectionState 3 => 5 [08:10:07:INFO] (RTCLogging.mm:33): (ARDAppClient.m:401 -[ARDAppClient peerConnection:didChangeIceConnectionState:]): ICE state changed: 5 [08:10:07:INFO] [001:305] [30487] (channel.cc:598): Channel not writable (0) [08:10:07:INFO] [001:305] [30487] (channel.cc:598): Channel not writable (1) [08:10:07:INFO] [001:305] [30487] (srtp_transport.cc:364): The params in SRTP transport are reset. [08:10:07:INFO] [001:305] [27919] (channel.cc:867): Changing voice state, recv=1 send=1 [08:10:07:INFO] [001:305] [50947] (peer_connection.cc:6824): Usage signature is 8184 [08:10:07:INFO] [001:305] [27919] (video_send_stream.cc:133): VideoSendStream::UpdateActiveSimulcastLayers [08:10:07:INFO] [001:305] [47879] (video_send_stream_impl.cc:363): VideoSendStream::UpdateActiveSimulcastLayers [08:10:07:INFO] [001:305] [27919] (channel.cc:1000): Changing video state, send=1 [08:10:07:INFO] [001:305] [50947] (peer_connection.cc:894): Session: 7061599334522676903 is destroyed. [08:10:07:INFO] [001:308] [50947] (dtmf_sender.cc:216): The Dtmf provider is deleted. Clear the sending queue. [08:10:07:INFO] [001:309] [50691] (webrtc_video_engine.cc:483): WebRtcVideoEngine::~WebRtcVideoEngine [08:10:07:INFO] [001:309] [50691] (webrtc_voice_engine.cc:205): WebRtcVoiceEngine::~WebRtcVoiceEngine [08:10:07:INFO] [001:309] [50691] (audio_device_module_ios.mm:532): StopPlayout [08:10:07:INFO] [001:309] [50691] (audio_device_ios.mm:249): AudioDeviceIOS::StopPlayout [08:10:07:INFO] [001:309] [50691] (audio_device_module_ios.mm:536): output: 0 [08:10:07:INFO] [001:309] [50691] (audio_device_module_ios.mm:563): StopRecording [08:10:07:INFO] [001:309] [50691] (audio_device_ios.mm:300): AudioDeviceIOS::StopRecording [08:10:07:INFO] [001:309] [50691] (audio_device_module_ios.mm:567): output: 0 [08:10:07:INFO] [001:309] [50691] (audio_device_module_ios.mm:581): RegisterAudioCallback [08:10:07:INFO] [001:309] [50691] (audio_device_buffer.cc:81): RegisterAudioCallback [08:10:07:INFO] [001:309] [50691] (audio_device_module_ios.mm:92): Terminate [08:10:07:INFO] [001:309] [50691] (audio_device_ios.mm:168): AudioDeviceIOS::Terminate [08:10:07:INFO] [001:309] [50691] (audio_device_ios.mm:249): AudioDeviceIOS::StopPlayout [08:10:07:INFO] [001:309] [50691] (audio_device_ios.mm:300): AudioDeviceIOS::StopRecording [08:10:07:INFO] [001:323] [50691] (audio_device_module_ios.mm:55): ~AudioDeviceModuleIOS [08:10:07:INFO] [001:323] [50691] (audio_device_buffer.cc:75): AudioDeviceBuffer::~dtor [08:10:07:INFO] [001:323] [50691] (audio_device_ios.mm:127): AudioDeviceIOS::~dtor<NSThread: 0x6000017b1340>{number = 4, name = (null)} [08:10:07:INFO] [001:323] [50691] (audio_device_ios.mm:168): AudioDeviceIOS::Terminate [08:10:07:INFO] [001:323] [50691] (fine_audio_buffer.cc:46): ~FineAudioBuffer [08:10:07:INFO] [001:323] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:347 Uninitialize): Unintializing audio unit. [08:10:07:INFO] [001:324] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:354 Uninitialize): Uninitialized audio unit. [08:10:07:INFO] [001:324] [50691] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:459 DisposeAudioUnit): Disposing audio unit. [08:10:07:INFO] Test Case '-[ARDAppClientTest testSession]' passed (1.335 seconds). [08:10:07:INFO] Test Suite 'ARDAppClientTest' passed at 2019-05-24 08:10:05.171. [08:10:07:INFO] Executed 1 test, with 0 failures (0 unexpected) in 1.335 (1.336) seconds [08:10:07:INFO] Test Suite 'ARDFileCaptureControllerTests' started at 2019-05-24 08:10:05.172 [08:10:07:INFO] Test Case '-[ARDFileCaptureControllerTests testCaptureIsStarted]' started. [08:10:07:INFO] Test Case '-[ARDFileCaptureControllerTests testCaptureIsStarted]' passed (0.001 seconds). [08:10:07:INFO] Test Case '-[ARDFileCaptureControllerTests testCaptureIsStoped]' started. [08:10:07:INFO] Test Case '-[ARDFileCaptureControllerTests testCaptureIsStoped]' passed (0.001 seconds). [08:10:07:INFO] Test Suite 'ARDFileCaptureControllerTests' passed at 2019-05-24 08:10:05.174. [08:10:07:INFO] Executed 2 tests, with 0 failures (0 unexpected) in 0.001 (0.002) seconds [08:10:07:INFO] Test Suite 'ARDSettingsModelTests' started at 2019-05-24 08:10:05.175 [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testConstraintComponentIsNilWhenInvalidConstraintString]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testConstraintComponentIsNilWhenInvalidConstraintString]' passed (0.017 seconds). [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testHeightConstraintFromStore]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testHeightConstraintFromStore]' passed (0.001 seconds). [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testRetrievingSetting]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testRetrievingSetting]' passed (0.001 seconds). [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testReturningDefaultCallOption]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testReturningDefaultCallOption]' passed (0.001 seconds). [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testStoringAudioSetting]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testStoringAudioSetting]' passed (0.001 seconds). [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testStoringInvalidConstraintReturnsNo]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testStoringInvalidConstraintReturnsNo]' passed (0.001 seconds). [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testWidthConstraintFromStore]' started. [08:10:07:INFO] Test Case '-[ARDSettingsModelTests testWidthConstraintFromStore]' passed (0.001 seconds). [08:10:07:INFO] Test Suite 'ARDSettingsModelTests' passed at 2019-05-24 08:10:05.214. [08:10:07:INFO] Executed 7 tests, with 0 failures (0 unexpected) in 0.023 (0.040) seconds [08:10:07:INFO] Test Suite 'apprtcmobile_tests.app' passed at 2019-05-24 08:10:05.215. [08:10:07:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 1.359 (1.379) seconds [08:10:07:INFO] Test Suite 'All tests' passed at 2019-05-24 08:10:05.215. [08:10:07:INFO] Executed 10 tests, with 0 failures (0 unexpected) in 1.359 (1.381) seconds [08:10:07:INFO] [001:371] [27919] (rtc_event_log_impl.cc:141): Stopping WebRTC event log. [08:10:07:INFO] [001:371] [27919] (rtc_event_log_impl.cc:153): WebRTC event log successfully stopped. [08:10:07:INFO] [001:377] [50187] (peer_connection.cc:4185): Session: 270370254968405980 Old state: kStable New state: kClosed [08:10:07:INFO] [001:377] [21251] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 0bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 10000000bps [08:10:07:INFO] [001:377] [27919] (audio_device_module_ios.mm:563): StopRecording [08:10:07:INFO] [001:377] [27919] (audio_device_ios.mm:300): AudioDeviceIOS::StopRecording [08:10:07:INFO] [001:377] [27919] (audio_device_buffer.cc:153): StopRecording [08:10:07:INFO] [001:378] [27919] (audio_device_buffer.cc:176): total recording time: 282 [08:10:07:INFO] [001:378] [27919] (audio_device_module_ios.mm:567): output: 0 [08:10:07:INFO] [001:378] [27919] (webrtc_voice_engine.cc:1969): SetOutputVolume() to 0 for recv stream with ssrc 4124656428 [08:10:07:INFO] [001:378] [27919] (webrtc_video_engine.cc:1040): SetVideoSend (ssrc= 617989588, options: nullptr, source = nullptr) [08:10:07:INFO] [001:378] [27919] (webrtc_video_engine.cc:1320): SetSink: ssrc:2623606793 nullptr [08:10:07:INFO] [001:378] [27919] (channel.cc:565): Channel disabled [08:10:07:INFO] [001:382] [27919] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:07:INFO] [001:382] [27919] (channel.cc:1000): Changing video state, send=0 [08:10:07:INFO] [001:382] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:382] [21251] (rtp_rtcp_impl.cc:360): Failed to send RTCP BYE [08:10:07:INFO] [001:382] [21251] (bitrate_allocator.cc:247): UpdateAllocationLimits : total_requested_min_bitrate: 0bps, total_requested_padding_bitrate: 0bps, total_requested_max_bitrate: 0bps [08:10:07:INFO] [001:382] [27919] (video_send_stream.cc:160): VideoSendStream::Stop [08:10:07:INFO] [001:382] [47879] (video_stream_encoder.cc:1604): Video suspend state changed to: suspended [08:10:07:INFO] [001:382] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:382] [21251] (video_send_stream_impl.cc:417): VideoSendStream::Stop [08:10:07:INFO] [001:382] [21251] (video_send_stream_impl.cc:338): ~VideoSendStreamInternal: {encoder_settings: { experiment_cpu_load_estimator: off}}, rtp: {ssrcs: [617989588], rtcp_mode: RtcpMode::kReducedSize, max_packet_size: 1200, extmap-allow-mixed: false, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}], lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec: {ulpfec_payload_type: 124, red_payload_type: 125, red_rtx_payload_type: 122}, payload_name: H264, payload_type: 96, raw_payload: false, flexfec: {payload_type: -1, ssrc: 0, protected_media_ssrcs: []}, rtx: {ssrcs: [1547243348], payload_type: 97}, c_name: ZUkKncj912NvzTaZ}, rtcp_report_interval_ms: 1000, send_transport: (Transport), media_transport: nullptr, render_delay_ms: 0, target_delay_ms: 0, suspend_below_min_bitrate: off} [08:10:07:INFO] [001:399] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:399] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:400] [27919] (send_statistics_proxy.cc:655): Frames encoded 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Capturer 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.EncoderQueue 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Encoder 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Ratelimiter 0 [08:10:07:INFO] [001:400] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:400] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:400] [27919] (video_receive_stream.cc:287): ~VideoReceiveStream: {decoders: [{payload_type: 96, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 640c1f}}, {payload_type: 98, payload_name: H264, codec_params: {level-asymmetry-allowed: 1packetization-mode: 1profile-level-id: 42e01f}}, {payload_type: 100, payload_name: VP8, codec_params: {}}, {payload_type: 127, payload_name: VP9, codec_params: {}}], rtp: {remote_ssrc: 2623606793, local_ssrc: 617989588, rtcp_mode: RtcpMode::kReducedSize, rtcp_xr: {receiver_reference_time_report: off}, remb: on, transport_cc: on, lntf: {enabled: false}, nack: {rtp_history_ms: 1000}, ulpfec_payload_type: 124, red_type: 125, rtx_ssrc: 3720308880, rtx_payload_types: {97 (pt) -> 96 (apt), 99 (pt) -> 98 (apt), 101 (pt) -> 100 (apt), 122 (pt) -> 125 (apt), 123 (pt) -> 127 (apt), }, raw_payload_types: {}, extensions: [{uri: http://tools.ietf.org/html/draft-ietf-avtext-framemarking-07, id: 8}, {uri: http://www.ietf.org/id/draft-holmer-rmcat-transport-wide-cc-extensions-01, id: 2}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time, id: 13}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/color-space, id: 9}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/playout-delay, id: 11}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-content-type, id: 6}, {uri: http://www.webrtc.org/experiments/rtp-hdrext/video-timing, id: 7}, {uri: urn:3gpp:video-orientation, id: 12}, {uri: urn:ietf:params:rtp-hdrext:sdes:mid, id: 3}, {uri: urn:ietf:params:rtp-hdrext:sdes:repaired-rtp-stream-id, id: 5}, {uri: urn:ietf:params:rtp-hdrext:sdes:rtp-stream-id, id: 4}, {uri: urn:ietf:params:rtp-hdrext:toffset, id: 14}]}, renderer: (renderer), render_delay_ms: 10, sync_group: ARDAMS, target_delay_ms: 0} [08:10:07:INFO] [001:410] [27919] (video_render_frames.cc:47): WebRTC.Video.DroppedFrames.RenderQueue 0 [08:10:07:INFO] [001:410] [27919] (receive_statistics_proxy.cc:491): Frames decoded 0 [08:10:07:INFO] WebRTC.Video.DroppedFrames.Receiver 0 [08:10:07:INFO] [08:10:07:INFO] [001:411] [27919] (channel.cc:163): Destroyed channel: 1 [08:10:07:INFO] [001:411] [27919] (channel.cc:565): Channel disabled [08:10:07:INFO] [001:411] [27919] (audio_device_module_ios.mm:532): StopPlayout [08:10:07:INFO] [001:411] [27919] (audio_device_ios.mm:249): AudioDeviceIOS::StopPlayout [08:10:07:INFO] [001:411] [27919] (audio_device_buffer.cc:139): StopPlayout [08:10:07:INFO] [001:411] [27919] (audio_device_buffer.cc:145): total playout time: 437 [08:10:07:INFO] [001:411] [27919] (audio_device_module_ios.mm:536): output: 0 [08:10:07:INFO] [001:411] [27919] (channel.cc:867): Changing voice state, recv=0 send=0 [08:10:07:INFO] [001:411] [27919] (webrtc_voice_engine.cc:1830): RemoveSendStream: 2255579466 [08:10:07:INFO] [001:411] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up [08:10:07:INFO] [001:411] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Up [08:10:07:INFO] [001:411] [27919] (audio_send_stream.cc:179): ~AudioSendStream: 2255579466 [08:10:07:INFO] [001:411] [27919] (webrtc_voice_engine.cc:1907): RemoveRecvStream: 4124656428 [08:10:07:INFO] [001:411] [27919] (call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down [08:10:07:INFO] [001:423] [27919] (rtp_transport_controller_send.cc:268): SignalNetworkState Down [08:10:07:INFO] [001:423] [27919] (audio_receive_stream.cc:140): ~AudioReceiveStream: 4124656428 [08:10:07:INFO] [001:423] [47879] (paced_sender.cc:106): PacedSender paused. [08:10:07:INFO] [001:423] [47879] (control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps. [08:10:07:INFO] [001:423] [27919] (channel.cc:163): Destroyed channel: 0 [08:10:07:INFO] [001:423] [30487] (openssl_stream_adapter.cc:896): Cleanup [08:10:07:INFO] [001:424] [27919] (paced_sender.cc:421): ProcessThreadAttached 0x0 [08:10:07:INFO] [001:424] [27919] (paced_sender.cc:421): ProcessThreadAttached 0x0 [08:10:07:INFO] [001:424] [50187] (peer_connection.cc:6824): Usage signature is 8184 [08:10:07:INFO] [001:424] [50187] (peer_connection.cc:894): Session: 270370254968405980 is destroyed. [08:10:07:INFO] [001:424] [50187] (dtmf_sender.cc:216): The Dtmf provider is deleted. Clear the sending queue. [08:10:07:INFO] [001:425] [27919] (webrtc_video_engine.cc:483): WebRtcVideoEngine::~WebRtcVideoEngine [08:10:07:INFO] [001:425] [27919] (webrtc_voice_engine.cc:205): WebRtcVoiceEngine::~WebRtcVoiceEngine [08:10:07:INFO] [001:435] [27919] (audio_device_module_ios.mm:532): StopPlayout [08:10:07:INFO] [001:435] [27919] (audio_device_ios.mm:249): AudioDeviceIOS::StopPlayout [08:10:07:INFO] [001:435] [27919] (audio_device_module_ios.mm:536): output: 0 [08:10:07:INFO] [001:435] [27919] (audio_device_module_ios.mm:563): StopRecording [08:10:07:INFO] [001:435] [27919] (audio_device_ios.mm:300): AudioDeviceIOS::StopRecording [08:10:07:INFO] [001:435] [27919] (audio_device_module_ios.mm:567): output: 0 [08:10:07:INFO] [001:435] [27919] (audio_device_module_ios.mm:581): RegisterAudioCallback [08:10:07:INFO] [001:435] [27919] (audio_device_buffer.cc:81): RegisterAudioCallback [08:10:07:INFO] [001:435] [27919] (audio_device_module_ios.mm:92): Terminate [08:10:07:INFO] [001:435] [27919] (audio_device_ios.mm:168): AudioDeviceIOS::Terminate [08:10:07:INFO] [001:435] [27919] (audio_device_ios.mm:249): AudioDeviceIOS::StopPlayout [08:10:07:INFO] [001:435] [27919] (audio_device_ios.mm:300): AudioDeviceIOS::StopRecording [08:10:07:INFO] [001:436] [27919] (audio_device_module_ios.mm:55): ~AudioDeviceModuleIOS [08:10:07:INFO] [001:436] [27919] (audio_device_buffer.cc:75): AudioDeviceBuffer::~dtor [08:10:07:INFO] [001:436] [27919] (audio_device_ios.mm:127): AudioDeviceIOS::~dtor<NSThread: 0x6000017bbc40>{number = 3, name = (null)} [08:10:07:INFO] [001:436] [27919] (audio_device_ios.mm:168): AudioDeviceIOS::Terminate [08:10:07:INFO] [001:436] [27919] (fine_audio_buffer.cc:46): ~FineAudioBuffer [08:10:07:INFO] [001:436] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:347 Uninitialize): Unintializing audio unit. [08:10:07:INFO] [001:437] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:354 Uninitialize): Uninitialized audio unit. [08:10:07:INFO] [001:437] [27919] (RTCLogging.mm:33): (voice_processing_audio_unit.mm:459 DisposeAudioUnit): Disposing audio unit. [08:10:07:INFO] [08:10:07:INFO] [08:10:07:INFO] Test session results and logs: [08:10:07:INFO] /Users/chrome-bot/Library/Developer/Xcode/DerivedData/temporary-bmrscbzryptugidvhrovakvldyve/Logs/Test/Test-Transient Testing-2019.05.24_08-09-45--0700.xcresult [08:10:07:INFO] [08:10:07:INFO] 2019-05-24 08:10:05.736 xcodebuild[3672:38205] [MT] IDETestOperationsObserverDebug: 19.947 elapsed -- Testing started completed. [08:10:07:INFO] 2019-05-24 08:10:05.736 xcodebuild[3672:38205] [MT] IDETestOperationsObserverDebug: 0.000 sec, +0.000 sec -- start [08:10:07:INFO] 2019-05-24 08:10:05.736 xcodebuild[3672:38205] [MT] IDETestOperationsObserverDebug: 19.947 sec, +19.947 sec -- end [08:10:07:INFO] ** TEST EXECUTE SUCCEEDED ** [08:10:07:INFO] [08:10:11:DEBUG] Finished print_process_output. [08:10:11:INFO] Waiting for test process to terminate. [08:10:11:INFO] Test process terminated. [08:10:11:DEBUG] Setting sigterm handler. [08:10:11:DEBUG] Stdout flushed after test process. [08:10:11:DEBUG] Processing test results. [08:10:11:INFO] /b/s/w/ir/src/out/Debug-iphonesimulator/iossim returned 0 [08:10:11:DEBUG] Extracting test data. [08:10:12:DEBUG] Retrieving crash reports. [08:10:12:DEBUG] Retrieving derived data. [08:10:12:DEBUG] Making desktop screenshots. [08:10:12:DEBUG] Killing simulators. [08:10:12:INFO] Killing simulators. [08:10:17:DEBUG] Wiping simulator. Device wiped. [08:10:18:DEBUG] End of tear_down. 2019-05-24 08:10:50,573 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/k/recipe_cleanup/01FpYdl/4503353fbfd24210'] 2019-05-24 08:10:50,574 - root: [WARNING] Expected output.json file missing: set(['/b/s/w/ir/k/recipe_cleanup/01FpYdl/4503353fbfd24210/output.json']) Found: [] Expected: ['/b/s/w/ir/k/recipe_cleanup/01FpYdl/4503353fbfd24210/output.json'] 2019-05-24 08:10:50,574 - root: [WARNING] No shard json files found in task_output_dir: '/b/s/w/ir/k/recipe_cleanup/01FpYdl' Found ['/b/s/w/ir/k/recipe_cleanup/01FpYdl/4503353fbfd24210'] 2019-05-24 08:10:50,574 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/k/recipe_cleanup/01FpYdl/4503353fbfd24210/output.json'] 2019-05-24 08:10:50,574 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/5b0713/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py --summary-json /b/s/w/ir/tmp/t/tmpkVvq3u.json --task-output-dir /b/s/w/ir/k/recipe_cleanup/01FpYdl -o /b/s/w/ir/tmp/t/tmpaO6Iuc.json Running ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpkVvq3u.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/01FpYdl', '-o', '/b/s/w/ir/tmp/t/tmpaO6Iuc.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/5b0713/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/noop_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpkVvq3u.json', '--task-output-dir', '/b/s/w/ir/k/recipe_cleanup/01FpYdl', '-o', '/b/s/w/ir/tmp/t/tmpaO6Iuc.json'] returned exit code 0