[11:36:22.092] New invocation is queued and will start shortly
[11:36:23.237] Starting the invocation (attempt 1)
[11:36:23.272] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:36:23.272] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:36:23.272] Buildbucket request:
{
"bucket": "luci.webrtc.ci",
"client_operation_id": "9084080687815506672",
"parameters_json": "{\"builder_name\":\"Linux32 Debug\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://webrtc.googlesource.com/src\",\"revision\":\"6d83592367c2e1fd0cf12018a7b2c86eb5158eea\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux32 Debug",
"scheduler_invocation_id:9084080687815506672",
"scheduler_job_id:webrtc/Linux32 Debug",
"user_agent:luci-scheduler",
"buildset:commit/git/6d83592367c2e1fd0cf12018a7b2c86eb5158eea",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/6d83592367c2e1fd0cf12018a7b2c86eb5158eea",
"gitiles_ref:refs/heads/master"
]
}
[11:36:24.302] Buildbucket response:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"created_by": "project:webrtc",
"created_ts": "1552908983358012",
"id": "8918650625824156368",
"lease_expiration_ts": "1553024183872616",
"lease_key": "1696777023",
"parameters_json": "{\"builder_name\": \"Linux32 Debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6d83592367c2e1fd0cf12018a7b2c86eb5158eea\"}}",
"project": "webrtc",
"result_details_json": "{\"properties\": {}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552908984057298",
"tags": [
"build_address:luci.webrtc.ci/Linux32 Debug/20627",
"builder:Linux32 Debug",
"buildset:commit/git/6d83592367c2e1fd0cf12018a7b2c86eb5158eea",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/6d83592367c2e1fd0cf12018a7b2c86eb5158eea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084080687815506672",
"scheduler_job_id:webrtc/Linux32 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8918650625824156368/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43a9682556e44d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552908984057382",
"url": "https://ci.chromium.org/b/8918650625824156368",
"utcnow_ts": "1552908984293786"
}
}
[11:36:24.302] Task URL: https://ci.chromium.org/b/8918650625824156368
[11:36:24.302] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:2:0) after 1m0s
[11:37:24.322] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:2:0)
[11:37:24.322] Timer tick, asking Buildbucket for the build status
[11:37:24.669] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:37:24.669] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:3:0) after 1m0s
[11:38:24.686] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:3:0)
[11:38:24.686] Timer tick, asking Buildbucket for the build status
[11:38:24.762] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:38:24.762] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:4:0) after 1m0s
[11:39:24.782] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:4:0)
[11:39:24.782] Timer tick, asking Buildbucket for the build status
[11:39:24.972] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:39:24.972] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:5:0) after 1m0s
[11:40:24.979] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:5:0)
[11:40:24.979] Timer tick, asking Buildbucket for the build status
[11:40:25.121] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:40:25.121] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:6:0) after 1m0s
[11:41:25.138] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:6:0)
[11:41:25.138] Timer tick, asking Buildbucket for the build status
[11:41:25.216] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:25.216] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:7:0) after 1m0s
[11:42:25.242] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:7:0)
[11:42:25.242] Timer tick, asking Buildbucket for the build status
[11:42:25.482] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:25.482] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:8:0) after 1m0s
[11:43:25.503] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:8:0)
[11:43:25.503] Timer tick, asking Buildbucket for the build status
[11:43:25.701] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:43:25.701] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:9:0) after 1m0s
[11:44:25.681] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:9:0)
[11:44:25.681] Timer tick, asking Buildbucket for the build status
[11:44:25.984] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:44:25.984] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:10:0) after 1m0s
[11:45:26.002] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:10:0)
[11:45:26.002] Timer tick, asking Buildbucket for the build status
[11:45:26.129] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:26.129] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:11:0) after 1m0s
[11:46:26.147] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:11:0)
[11:46:26.147] Timer tick, asking Buildbucket for the build status
[11:46:26.436] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:26.436] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:12:0) after 1m0s
[11:47:26.422] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:12:0)
[11:47:26.422] Timer tick, asking Buildbucket for the build status
[11:47:26.672] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:26.672] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:13:0) after 1m0s
[11:48:26.696] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:13:0)
[11:48:26.696] Timer tick, asking Buildbucket for the build status
[11:48:26.813] Build 8918650625824156368: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:26.813] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:14:0) after 1m0s
[11:49:12.533] Received PubSub notification, asking Buildbucket for the build status
[11:49:12.703] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:26.819] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:14:0)
[11:49:26.819] Timer tick, asking Buildbucket for the build status
[11:49:26.905] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:26.905] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:16:0) after 1m0s
[11:50:26.922] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:16:0)
[11:50:26.922] Timer tick, asking Buildbucket for the build status
[11:50:27.039] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:27.039] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:17:0) after 1m0s
[11:51:27.063] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:17:0)
[11:51:27.063] Timer tick, asking Buildbucket for the build status
[11:51:27.393] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:27.393] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:18:0) after 1m0s
[11:52:27.396] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:18:0)
[11:52:27.396] Timer tick, asking Buildbucket for the build status
[11:52:27.587] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:27.587] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:19:0) after 1m0s
[11:53:27.599] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:19:0)
[11:53:27.599] Timer tick, asking Buildbucket for the build status
[11:53:27.657] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:27.657] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:20:0) after 1m0s
[11:54:27.627] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:20:0)
[11:54:27.627] Timer tick, asking Buildbucket for the build status
[11:54:27.699] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:27.699] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:21:0) after 1m0s
[11:55:27.671] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:21:0)
[11:55:27.671] Timer tick, asking Buildbucket for the build status
[11:55:27.717] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:27.718] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:22:0) after 1m0s
[11:56:27.737] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:22:0)
[11:56:27.737] Timer tick, asking Buildbucket for the build status
[11:56:27.889] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:27.889] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:23:0) after 1m0s
[11:57:27.863] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:23:0)
[11:57:27.863] Timer tick, asking Buildbucket for the build status
[11:57:28.046] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:28.046] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:24:0) after 1m0s
[11:58:28.046] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:24:0)
[11:58:28.046] Timer tick, asking Buildbucket for the build status
[11:58:28.174] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:28.174] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:25:0) after 1m0s
[11:59:28.231] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:25:0)
[11:59:28.231] Timer tick, asking Buildbucket for the build status
[11:59:28.283] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:28.283] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:26:0) after 1m0s
[12:00:28.303] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:26:0)
[12:00:28.303] Timer tick, asking Buildbucket for the build status
[12:00:28.377] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:28.377] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:27:0) after 1m0s
[12:01:28.378] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:27:0)
[12:01:28.378] Timer tick, asking Buildbucket for the build status
[12:01:28.488] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:28.489] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:28:0) after 1m0s
[12:02:28.508] Handling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:28:0)
[12:02:28.508] Timer tick, asking Buildbucket for the build status
[12:02:28.589] Build 8918650625824156368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:28.589] Scheduling timer "check-buildbucket-build-status" (webrtc/Linux32 Debug:9084080687815506672:29:0) after 1m0s
[12:02:44.712] Received PubSub notification, asking Buildbucket for the build status
[12:02:44.759] Build 8918650625824156368: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[12:02:44.759] Buildbucket build:
{
"build": {
"bucket": "luci.webrtc.ci",
"canary_preference": "AUTO",
"completed_ts": "1552910563898277",
"created_by": "project:webrtc",
"created_ts": "1552908983358012",
"id": "8918650625824156368",
"parameters_json": "{\"builder_name\": \"Linux32 Debug\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://webrtc.googlesource.com/src\", \"revision\": \"6d83592367c2e1fd0cf12018a7b2c86eb5158eea\"}}",
"project": "webrtc",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"4201-b04f7f8\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"trusty-webrtc-d2a17ac3-us-central1-b-2vg8\"], \"pool\": [\"luci.webrtc.ci\"], \"caches\": [\"builder_44678c1ef4c7d705bd7e592df97e9c1573c4ed9129da6654b149d3b42d045362_v2\", \"builder_cdf6b6e203c233fe6f1135d67c10c4b259db9d158a1efc5383f46762f6319be3_v2\", \"builder_cdf9911d0fb982b144366a069b820bab2cbe2663c4d5f6cc5be539135cc71a3a_v2\", \"builder_d8087e4e6918034fd84666cddd7a396a0a7b057decc19643f05e08f84b97a27e_v2\", \"builder_ee30cfe615ad79242c90cf868fc3e32771e3342e63241239745f710a5c60d840_v2\", \"git\", \"goma_v2\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"repository\": \"https://webrtc.googlesource.com/src\", \"buildername\": \"Linux32 Debug\", \"got_revision\": \"6d83592367c2e1fd0cf12018a7b2c86eb5158eea\", \"recipe\": \"webrtc/standalone\", \"mastername\": \"client.webrtc\", \"buildnumber\": 20627, \"isolate_server\": \"https://isolateserver.appspot.com\", \"got_revision_cp\": \"refs/heads/master@{#27159}\", \"swarm_hashes\": {\"common_audio_unittests\": \"dba0fcab67619333f1833feba9b98f95858c9cba\", \"system_wrappers_unittests\": \"0e1628fa5bd9603c78aca0bb6c0b4a877e6dbac0\", \"rtc_unittests\": \"9b7d8b74e0c769db568eaa75a34ed7b4eb15166d\", \"test_support_unittests\": \"7876515e13992cd2317845866b177acbdcbb6fea\", \"tools_unittests\": \"f55a1b686a0ba80df47500a19ca86404c74d869f\", \"slow_tests\": \"50898f3bfc7e9f310b01315193146249c98b8327\", \"rtc_pc_unittests\": \"67ae008d7b4384cc1d3559f98199f80b835b7bbc\", \"modules_tests\": \"a7f8a34ed4131c1bfc834b5cbd78157dac8fe3e5\", \"common_video_unittests\": \"53fa5e4e136b347c398ed00eb075313cb7001e9d\", \"video_engine_tests\": \"d781ed307e2fc82dd589084e38d6149b8f834303\", \"low_bandwidth_audio_test\": \"1db44efd4dc48ad8b72b51b11476a546bbbbe0ab\", \"webrtc_nonparallel_tests\": \"256f6bf63d3ab9dae16e17e22881056c2259be38\", \"peerconnection_unittests\": \"c923178cb4b874a3d58955d2563d5a2c6f22ead4\", \"rtc_stats_unittests\": \"7eadcb2e0cf72048e8a41629afaee7e3beefc82c\", \"audio_decoder_unittests\": \"ce689111e67815103f9144aa483b947bfb212b37\", \"modules_unittests\": \"008d7fce3ec5234f9459f791e9a3dd2361435f1e\", \"rtc_media_unittests\": \"103243966f4e4ca03040476db659f97a13d7a008\"}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"6d83592367c2e1fd0cf12018a7b2c86eb5158eea\", \"bot_id\": \"trusty-webrtc-d2a17ac3-us-central1-b-2vg8\"}}",
"service_account": "webrtc-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552909751876375",
"status": "COMPLETED",
"status_changed_ts": "1552910564454852",
"tags": [
"build_address:luci.webrtc.ci/Linux32 Debug/20627",
"builder:Linux32 Debug",
"buildset:commit/git/6d83592367c2e1fd0cf12018a7b2c86eb5158eea",
"buildset:commit/gitiles/webrtc.googlesource.com/src/+/6d83592367c2e1fd0cf12018a7b2c86eb5158eea",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084080687815506672",
"scheduler_job_id:webrtc/Linux32 Debug",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/webrtc/buildbucket/cr-buildbucket.appspot.com/8918650625824156368/+/annotations",
"swarming_tag:luci_project:webrtc",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:webrtc/standalone",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43a9682556e44d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552910564459149",
"url": "https://ci.chromium.org/b/8918650625824156368",
"utcnow_ts": "1552910564750607"
}
}
[12:02:44.759] Invocation finished in 26m22.683258591s with status SUCCEEDED