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

{ "shards": [ { "bot_dimensions": [ { "key": "caches", "value": [ "swarming_module_cache_vpython" ] }, { "key": "cores", "value": [ "8" ] }, { "key": "cpu", "value": [ "x86", "x86-64", "x86-64-E3-1230_v3", "x86-64-avx2" ] }, { "key": "cpu_governor", "value": [ "ondemand" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "gpu", "value": [ "102b", "102b:0534" ] }, { "key": "id", "value": [ "build17-m3" ] }, { "key": "inside_docker", "value": [ "0" ] }, { "key": "kvm", "value": [ "1" ] }, { "key": "locale", "value": [ "en_US.UTF-8" ] }, { "key": "machine_type", "value": [ "n1-standard-8" ] }, { "key": "os", "value": [ "Linux", "Ubuntu", "Ubuntu-14.04" ] }, { "key": "pool", "value": [ "WebRTC-perf" ] }, { "key": "python", "value": [ "2.7.6" ] }, { "key": "server_version", "value": [ "4303-bae210e" ] }, { "key": "zone", "value": [ "us", "us-atl", "us-atl-golo", "us-atl-golo-m3" ] } ], "bot_id": "build17-m3", "bot_version": "ec327ca04645110c43b092f9eb2d259c2461902ca791b7bcf390931d49bda575", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/linux-amd64", "version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC" }, "packages": [ { "package_name": "infra/python/cpython/linux-amd64", "path": ".swarming_module", "version": "46c0c897ca0f053799ee41fd148bb7a47232df47" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": ".swarming_module", "version": "2737ea8ed9b958f4d5aa9ffe106115a649ada241" }, { "package_name": "infra/tools/luci/vpython-native/linux-amd64", "path": ".swarming_module", "version": "tfTmX4aGCfFtfQucj_nEfwiNisK0RmpKrITkZMBBq3cC" }, { "package_name": "infra/tools/luci/vpython/linux-amd64", "path": ".swarming_module", "version": "bmnYyAg9mbM_538s1dIP1hZZmOtc8C7ssF41ztN2cr0C" } ] }, "completed_ts": "2019-05-25T00:11:03.519160", "costs_usd": [ 0.003955605487496167 ], "created_ts": "2019-05-25T00:09:28.025474", "duration": 26.534971952438354, "modified_ts": "2019-05-25T00:11:03.519160", "name": "low_bandwidth_audio_perf_test/Ubuntu-14.04/471649d40e/Perf Linux Trusty/1170", "output": "INFO:root:Running ['/b/s/w/ir/tools_webrtc/audio_quality/linux/PolqaOem64', '-q', '-LC', 'NB', '-Ref', '/b/s/w/ir/resources/voice_engine/audio_tiny48.wav', '-Test', '/b/s/w/ir/resources/voice_engine/audio_tiny48.wav']\nINFO:root:Running ['./low_bandwidth_audio_test', '--sample_rate_hz=16000']\n[==========] Running 2 tests from 1 test suite.\n[----------] Global test environment set-up.\n[----------] 2 tests from LowBandwidthAudioTest\n[ RUN ] LowBandwidthAudioTest.GoodNetworkHighBitrate\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x540a9940\n(cpu_info.cc:53): Available number of cores: 8\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x540b22f0\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(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\n(audio_coding_module.cc:311): Created\n(audio_send_stream.cc:142): AudioSendStream: 3735928559\n(audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 3735928559, extmap-allow-mixed: false, extensions: [], c_name: }, 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: {nack_enabled: false, transport_cc_enabled: false, cng_payload_type: <unset>, payload_type: 103, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {stereo: 1}}}}\n(audio_encoder_opus.cc:767): Set Opus bitrate to 64000 bps.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(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\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(audio_coding_module.cc:311): Created\n(audio_receive_stream.cc:117): AudioReceiveStream: 3735928559\n(audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 3735928559, local_ssrc: 19088743, transport_cc: off, nack: {rtp_history_ms: 0}, extensions: []}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}}\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probINFO:root:Running ['/b/s/w/ir/tools_webrtc/audio_quality/linux/pesq', '+16000', 'audio_tiny16.wav', 'LowBandwidth_GoodNetworkHighBitrate_16.wav']\ne further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(rtp_sender_audio.cc:260): First audio RTP packet sent to pacer\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(fake_network_pipe.cc:212): Network queue: 5 ms.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_send_stream.cc:179): ~AudioSendStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_receive_stream.cc:140): ~AudioReceiveStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\nTEST GoodNetworkHighBitrate /b/s/w/ir/resources/voice_engine/audio_tiny16.wav /b/s/w/ir/out/LowBandwidth_GoodNetworkHighBitrate_16.wav\nRESULT pesq_mos: GoodNetworkHighBitrate= 3.050 score\n[ OK ] LowBandwidthAudioTest.GoodNetworkHighBitrate (5913 ms)\n[ RUN ] LowBandwidthAudioTest.Mobile2GNetwork\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x540bc430\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x5409c400\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(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\n(audio_coding_module.cc:311): Created\n(audio_send_stream.cc:142): AudioSendStream: 3735928559\n(audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 3735928559, extmap-allow-mixed: false, extensions: [], c_name: }, 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: {nack_enabled: false, transport_cc_enabled: false, cng_payload_type: <unset>, payload_type: 103, format: {name: OPUS, clockrate_hz: 48000, num_channels: 2, parameters: {maxaveragebitrate: 6000, ptime: 60, stereo: 1}}}}\n(audio_encoder_opus.cc:767): Set Opus bitrate to 6000 bps.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(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\n(audio_coding_module.cc:311): Created\n(audio_receive_stream.cc:117): AudioReceiveStream: 3735928559\n(audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 3735928559, local_ssrc: 19088743, transport_cc: off, nack: {rtp_history_ms: 0}, extensions: []}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}}\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(rtp_sender_audio.cc:260): First audio RTP packet sent to pacer\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(fake_network_pipe.cc:212): Network queue: 460 ms.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_send_stream.cc:179): ~AudioSendStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_receive_stream.cc:140): ~AudioReceiveStream: 3735928559\n(paced_sender.cc:106): PacedSenINFO:root:Running ['/b/s/w/ir/tools_webrtc/audio_quality/linux/pesq', '+16000', 'audio_tiny16.wav', 'LowBandwidth_Mobile2GNetwork_16.wav']\nINFO:root:Running ['./low_bandwidth_audio_test', '--sample_rate_hz=48000']\nder paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\nTEST Mobile2GNetwork /b/s/w/ir/resources/voice_engine/audio_tiny16.wav /b/s/w/ir/out/LowBandwidth_Mobile2GNetwork_16.wav\nRESULT pesq_mos: Mobile2GNetwork= 2.151 score\n[ OK ] LowBandwidthAudioTest.Mobile2GNetwork (6318 ms)\n[----------] 2 tests from LowBandwidthAudioTest (12231 ms total)\n\n[----------] Global test environment tear-down\n[==========] 2 tests from 1 test suite ran. (12231 ms total)\n[ PASSED ] 2 tests.\n[==========] Running 2 tests from 1 test suite.\n[----------] Global test environment set-up.\n[----------] 2 tests from LowBandwidthAudioTest\n[ RUN ] LowBandwidthAudioTest.GoodNetworkHighBitrate\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x740a9e50\n(cpu_info.cc:53): Available number of cores: 8\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x740b2810\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(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\n(audio_coding_module.cc:311): Created\n(audio_send_stream.cc:142): AudioSendStream: 3735928559\n(audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 3735928559, extmap-allow-mixed: false, extensions: [], c_name: }, 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: {nack_enabled: false, transport_cc_enabled: false, cng_payload_type: <unset>, payload_type: 103, format: {name: opus, clockrate_hz: 48000, num_channels: 2, parameters: {stereo: 1}}}}\n(audio_encoder_opus.cc:767): Set Opus bitrate to 64000 bps.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(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\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(audio_coding_module.cc:311): Created\n(audio_receive_stream.cc:117): AudioReceiveStream: 3735928559\n(audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 3735928559, local_ssrc: 19088743, transport_cc: off, nack: {rtp_history_ms: 0}, extensions: []}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}}\n(call.cc:INFO:root:Running ['/b/s/w/ir/tools_webrtc/audio_quality/linux/PolqaOem64', '-q', '-LC', 'NB', '-Ref', './../audio_tiny48.wav', '-Test', './../LowBandwidth_GoodNetworkHighBitrate_48.wav']\n1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(rtp_sender_audio.cc:260): First audio RTP packet sent to pacer\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(fake_network_pipe.cc:212): Network queue: 5 ms.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_send_stream.cc:179): ~AudioSendStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_receive_stream.cc:140): ~AudioReceiveStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\nTEST GoodNetworkHighBitrate /b/s/w/ir/resources/voice_engine/audio_tiny48.wav /b/s/w/ir/out/LowBandwidth_GoodNetworkHighBitrate_48.wav\nRESULT polqa_mos_lqo: GoodNetworkHighBitrate= 4.5000 score\n[ OK ] LowBandwidthAudioTest.GoodNetworkHighBitrate (5914 ms)\n[ RUN ] LowBandwidthAudioTest.Mobile2GNetwork\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(audio_processing_impl.cc:435): Capture analyzer activated: 0\nCapture post processor activated: 0\nRender pre processor activated: 0\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x740bec10\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(bitrate_prober.cc:66): Bandwidth probing enabled, set to inactive\n(paced_sender.cc:421): ProcessThreadAttached 0x74004c20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(remote_bitrate_estimator_single_stream.cc:71): RemoteBitrateEstimatorSingleStream: Instantiating.\n(remote_estimator_proxy.cc:44): Maximum interval between transport feedback RTCP messages (ms): 250\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(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\n(audio_coding_module.cc:311): Created\n(audio_send_stream.cc:142): AudioSendStream: 3735928559\n(audio_send_stream.cc:230): AudioSendStream::ConfigureStream: {rtp: {ssrc: 3735928559, extmap-allow-mixed: false, extensions: [], c_name: }, 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: {nack_enabled: false, transport_cc_enabled: false, cng_payload_type: <unset>, payload_type: 103, format: {name: OPUS, clockrate_hz: 48000, num_channels: 2, parameters: {maxaveragebitrate: 6000, ptime: 60, stereo: 1}}}}\n(audio_encoder_opus.cc:767): Set Opus bitrate to 6000 bps.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(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\n(audio_coding_module.cc:311): Created\n(audio_receive_stream.cc:117): AudioReceiveStream: 3735928559\n(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\n(audio_receive_stream.cc:361): AudioReceiveStream::ConfigureStream: {rtp: {remote_ssrc: 3735928559, local_ssrc: 19088743, transport_cc: off, nack: {rtp_history_ms: 0}, extensions: []}, rtcp_send_transport: (Transport), media_transport_config: {media_transport: null}}\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=up\n(rtp_transport_controller_send.cc:268): SignalNetworkState Up\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(rtp_transport_controller_send.cc:462): Creating fallback congestion controller\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(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\n(trendline_estimator.cc:121): Using Trendline filter for delay change estimation with window size 20\n(aimd_rate_control.cc:105): Using aimd rate control with back off factor 0.85\n(delay_based_bwe.cc:267): BWE Setting start bitrate to: 300 kbps\n(probe_controller.cc:266): Measured bitrate: 300000 Minimum to probe further: 1260000\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (900000:1687:5)\n(bitrate_prober.cc:114): Probe cluster (bitrate:min bytes:min packets): (1800000:3375:5)\n(bitrate_allocator.cc:117): Current BWE 300000\n(rtp_sender_audio.cc:260): First audio RTP packet senINFO:root:Running ['/b/s/w/ir/tools_webrtc/audio_quality/linux/PolqaOem64', '-q', '-LC', 'NB', '-Ref', './../audio_tiny48.wav', '-Test', './../LowBandwidth_Mobile2GNetwork_48.wav']\nt to pacer\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(probe_controller.cc:364): kWaitingForProbingResult: timeout\n(fake_network_pipe.cc:212): Network queue: 460 ms.\n(fake_network_pipe.cc:212): Network queue: 5 ms.\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_send_stream.cc:179): ~AudioSendStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(call.cc:1169): UpdateAggregateNetworkState: aggregate_state=down\n(rtp_transport_controller_send.cc:268): SignalNetworkState Down\n(audio_receive_stream.cc:140): ~AudioReceiveStream: 3735928559\n(paced_sender.cc:106): PacedSender paused.\n(control_handler.cc:77): Bitrate estimate state changed, BWE: 300 kbps.\n(bitrate_allocator.cc:117): Current BWE 0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\n(paced_sender.cc:421): ProcessThreadAttached 0x0\nTEST Mobile2GNetwork /b/s/w/ir/resources/voice_engine/audio_tiny48.wav /b/s/w/ir/out/LowBandwidth_Mobile2GNetwork_48.wav\nRESULT polqa_mos_lqo: Mobile2GNetwork= 3.0958 score\n[ OK ] LowBandwidthAudioTest.Mobile2GNetwork (6313 ms)\n[----------] 2 tests from LowBandwidthAudioTest (12227 ms total)\n\n[----------] Global test environment tear-down\n[==========] 2 tests from 1 test suite ran. (12227 ms total)\n[ PASSED ] 2 tests.\n", "outputs_ref": { "isolated": "7707b9d5432efc9df387d0865bd9c663233721b2", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "450523af0ce38e11", "server_versions": [ "4303-bae210e" ], "started_ts": "2019-05-25T00:10:30.146277", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:Perf Linux Trusty", "buildnumber:1170", "cpu:x86-64", "data:471649d40e4468d1f42ec90e802f7b3eb208341b", "master:client.webrtc.perf", "name:low_bandwidth_audio_perf_test", "os:Ubuntu-14.04", "pool:WebRTC-perf", "priority:25", "project:webrtc", "purpose:CI", "purpose:luci", "purpose:post-commit", "service_account:none", "slavename:swarm-docker-0-c3--004", "spec_name:webrtc.perf:Perf Linux Trusty", "stepname:low_bandwidth_audio_perf_test", "swarming.pool.template:none", "swarming.pool.version:ccd91245d277b60137b7f72e33ea6072eedcc9f9", "user:None" ], "task_id": "450523af0ce38e10", "try_number": "1" } ] }