[02:28:44.686] New invocation is queued and will start shortly
[02:28:45.851] Starting the invocation (attempt 1)
[02:28:45.889] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:28:45.889] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:28:45.889] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021875025418532624",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a003c087798edb7f74c2f6389328a0c45b66d5a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9021875025418532624",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5a003c087798edb7f74c2f6389328a0c45b66d5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a003c087798edb7f74c2f6389328a0c45b66d5a",
"gitiles_ref:refs/heads/master"
]
}
[02:28:46.642] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612232925999388",
"id": "8856444963345760176",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a003c087798edb7f74c2f6389328a0c45b66d5a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612232926477619",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180182",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5a003c087798edb7f74c2f6389328a0c45b66d5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a003c087798edb7f74c2f6389328a0c45b66d5a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021875025418532624",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856444963345760176/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1612232926477742",
"url": "https://ci.chromium.org/b/8856444963345760176",
"utcnow_ts": "1612232926634985"
}
}
[02:28:46.642] Task URL: https://ci.chromium.org/b/8856444963345760176
[02:28:46.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:2:0) after 1m0s
[02:29:05.238] Received PubSub notification, asking Buildbucket for the build status
[02:29:05.387] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:46.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:2:0)
[02:29:46.649] Timer tick, asking Buildbucket for the build status
[02:29:46.859] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:46.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:4:0) after 1m0s
[02:30:46.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:4:0)
[02:30:46.913] Timer tick, asking Buildbucket for the build status
[02:30:47.015] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:47.015] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:5:0) after 1m0s
[02:31:47.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:5:0)
[02:31:47.069] Timer tick, asking Buildbucket for the build status
[02:31:47.322] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:47.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:6:0) after 1m0s
[02:32:47.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:6:0)
[02:32:47.385] Timer tick, asking Buildbucket for the build status
[02:32:47.890] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:47.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:7:0) after 1m0s
[02:33:47.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:7:0)
[02:33:47.921] Timer tick, asking Buildbucket for the build status
[02:33:47.985] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:47.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:8:0) after 1m0s
[02:34:48.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:8:0)
[02:34:48.003] Timer tick, asking Buildbucket for the build status
[02:34:48.122] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:48.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:9:0) after 1m0s
[02:35:48.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:9:0)
[02:35:48.156] Timer tick, asking Buildbucket for the build status
[02:35:48.230] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:48.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:10:0) after 1m0s
[02:36:48.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:10:0)
[02:36:48.288] Timer tick, asking Buildbucket for the build status
[02:36:48.400] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:48.400] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:11:0) after 1m0s
[02:37:48.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:11:0)
[02:37:48.425] Timer tick, asking Buildbucket for the build status
[02:37:48.705] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:48.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:12:0) after 1m0s
[02:38:48.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:12:0)
[02:38:48.705] Timer tick, asking Buildbucket for the build status
[02:38:48.957] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:48.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:13:0) after 1m0s
[02:39:49.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:13:0)
[02:39:49.072] Timer tick, asking Buildbucket for the build status
[02:39:49.174] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:49.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:14:0) after 1m0s
[02:40:49.203] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:14:0)
[02:40:49.203] Timer tick, asking Buildbucket for the build status
[02:40:49.311] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:49.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:15:0) after 1m0s
[02:41:49.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:15:0)
[02:41:49.394] Timer tick, asking Buildbucket for the build status
[02:41:49.557] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:49.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:16:0) after 1m0s
[02:42:49.581] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:16:0)
[02:42:49.581] Timer tick, asking Buildbucket for the build status
[02:42:49.818] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:49.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:17:0) after 1m0s
[02:43:49.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:17:0)
[02:43:49.906] Timer tick, asking Buildbucket for the build status
[02:43:50.065] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:50.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:18:0) after 1m0s
[02:44:50.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:18:0)
[02:44:50.087] Timer tick, asking Buildbucket for the build status
[02:44:50.147] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:50.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:19:0) after 1m0s
[02:45:50.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:19:0)
[02:45:50.226] Timer tick, asking Buildbucket for the build status
[02:45:50.586] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:50.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:20:0) after 1m0s
[02:46:50.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:20:0)
[02:46:50.614] Timer tick, asking Buildbucket for the build status
[02:46:50.721] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:50.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:21:0) after 1m0s
[02:47:50.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:21:0)
[02:47:50.796] Timer tick, asking Buildbucket for the build status
[02:47:50.955] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:50.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:22:0) after 1m0s
[02:48:51.031] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:22:0)
[02:48:51.031] Timer tick, asking Buildbucket for the build status
[02:48:51.686] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:51.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:23:0) after 1m0s
[02:49:51.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:23:0)
[02:49:51.732] Timer tick, asking Buildbucket for the build status
[02:49:51.813] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:51.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:24:0) after 1m0s
[02:50:51.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:24:0)
[02:50:51.815] Timer tick, asking Buildbucket for the build status
[02:50:51.875] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:51.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:25:0) after 1m0s
[02:51:51.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:25:0)
[02:51:51.855] Timer tick, asking Buildbucket for the build status
[02:51:51.928] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:51.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:26:0) after 1m0s
[02:52:52.002] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:26:0)
[02:52:52.002] Timer tick, asking Buildbucket for the build status
[02:52:52.064] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:52.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:27:0) after 1m0s
[02:53:52.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:27:0)
[02:53:52.117] Timer tick, asking Buildbucket for the build status
[02:53:52.316] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:52.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:28:0) after 1m0s
[02:54:52.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:28:0)
[02:54:52.408] Timer tick, asking Buildbucket for the build status
[02:54:52.557] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:52.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:29:0) after 1m0s
[02:55:52.594] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:29:0)
[02:55:52.594] Timer tick, asking Buildbucket for the build status
[02:55:52.680] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:52.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:30:0) after 1m0s
[02:56:52.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:30:0)
[02:56:52.701] Timer tick, asking Buildbucket for the build status
[02:56:53.032] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:31:0) after 1m0s
[02:57:53.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:31:0)
[02:57:53.093] Timer tick, asking Buildbucket for the build status
[02:57:53.243] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:32:0) after 1m0s
[02:58:53.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:32:0)
[02:58:53.277] Timer tick, asking Buildbucket for the build status
[02:58:53.401] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:53.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:33:0) after 1m0s
[02:59:53.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:33:0)
[02:59:53.422] Timer tick, asking Buildbucket for the build status
[02:59:53.486] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:53.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:34:0) after 1m0s
[03:00:53.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:34:0)
[03:00:53.630] Timer tick, asking Buildbucket for the build status
[03:01:04.889] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:04.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:35:0) after 1m0s
[03:02:04.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:35:0)
[03:02:04.912] Timer tick, asking Buildbucket for the build status
[03:02:05.156] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:05.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:36:0) after 1m0s
[03:03:05.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:36:0)
[03:03:05.190] Timer tick, asking Buildbucket for the build status
[03:03:05.257] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:05.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:37:0) after 1m0s
[03:04:05.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:37:0)
[03:04:05.301] Timer tick, asking Buildbucket for the build status
[03:04:05.470] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:05.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:38:0) after 1m0s
[03:05:05.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:38:0)
[03:05:05.519] Timer tick, asking Buildbucket for the build status
[03:05:05.805] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:05.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:39:0) after 1m0s
[03:06:05.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:39:0)
[03:06:05.898] Timer tick, asking Buildbucket for the build status
[03:06:06.303] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:06.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:40:0) after 1m0s
[03:07:06.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:40:0)
[03:07:06.378] Timer tick, asking Buildbucket for the build status
[03:07:06.603] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:06.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:41:0) after 1m0s
[03:08:06.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:41:0)
[03:08:06.636] Timer tick, asking Buildbucket for the build status
[03:08:06.794] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:06.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:42:0) after 1m0s
[03:09:06.799] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:42:0)
[03:09:06.799] Timer tick, asking Buildbucket for the build status
[03:09:06.910] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:06.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:43:0) after 1m0s
[03:10:06.926] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:43:0)
[03:10:06.926] Timer tick, asking Buildbucket for the build status
[03:10:07.066] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:07.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:44:0) after 1m0s
[03:11:07.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:44:0)
[03:11:07.141] Timer tick, asking Buildbucket for the build status
[03:11:07.411] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:07.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:45:0) after 1m0s
[03:12:07.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:45:0)
[03:12:07.452] Timer tick, asking Buildbucket for the build status
[03:12:07.712] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:07.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:46:0) after 1m0s
[03:13:07.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:46:0)
[03:13:07.728] Timer tick, asking Buildbucket for the build status
[03:13:07.903] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:07.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:47:0) after 1m0s
[03:14:07.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:47:0)
[03:14:07.971] Timer tick, asking Buildbucket for the build status
[03:14:08.287] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:08.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:48:0) after 1m0s
[03:15:08.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:48:0)
[03:15:08.366] Timer tick, asking Buildbucket for the build status
[03:15:08.512] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:08.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:49:0) after 1m0s
[03:16:08.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:49:0)
[03:16:08.540] Timer tick, asking Buildbucket for the build status
[03:16:08.926] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:08.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:50:0) after 1m0s
[03:17:08.973] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:50:0)
[03:17:08.973] Timer tick, asking Buildbucket for the build status
[03:17:09.536] Build 8856444963345760176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:09.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9021875025418532624:51:0) after 1m0s
[03:17:47.897] Received PubSub notification, asking Buildbucket for the build status
[03:17:47.979] Build 8856444963345760176: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:17:47.979] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612235867032781",
"created_by": "project:chromium",
"created_ts": "1612232925999388",
"id": "8856444963345760176",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a003c087798edb7f74c2f6389328a0c45b66d5a\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"5a003c087798edb7f74c2f6389328a0c45b66d5a\", \"project\": \"chromium/src\"}], \"$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}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-72-rtao\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180182, \"got_angle_revision\": \"0d175f30a81b75da39026d65637bd05a2d585f7a\", \"got_dawn_revision\": \"91e737b8f16a69600f6a391fff127facbdf9e7e0\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"5a003c087798edb7f74c2f6389328a0c45b66d5a\", \"got_revision_cp\": \"refs/heads/master@{#849380}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"5093aadcdb5014db8a3f73e6ac488f8272d48e38\", \"got_v8_revision_cp\": \"refs/heads/9.0.107@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a003c087798edb7f74c2f6389328a0c45b66d5a\", \"swarm_hashes_refs/heads/master(at){#849380}_without_patch\": {\"absl_hardening_tests\": \"08f16b8c56bce3a3384945a98de97d0b5fff3724\", \"accessibility_unittests\": \"2923d24a8c6bacd99419f8484986f1de18c9bf69\", \"angle_unittests\": \"d136317e23e8957234afb90e213cd4403fdc1d0c\", \"app_shell_unittests\": \"9b51aa9ca0a4dfe97bb190694dc231737c659c2a\", \"aura_unittests\": \"cd5a7794b2b26aca8532c24d2ae1d02de4bf5ec0\", \"base_unittests\": \"76d9e946695e74a7f5533bf60b710d2e602f076a\", \"base_util_unittests\": \"d390dfd7d151e00624c5af8b887c76779871856f\", \"blink_common_unittests\": \"ad6ae0939ea175eb3427a499b02559bba5a9ff9f\", \"blink_fuzzer_unittests\": \"a6c7daec3cc3ddf7eb841925f3049a4cb7241bf1\", \"blink_heap_unittests\": \"7f8a1275c24480ad2e6949e394893d2f9ad5c486\", \"blink_platform_unittests\": \"0ee885b23426f035b79e97bf21c0eb3730f43b48\", \"blink_python_tests\": \"862c9d0fca9f01bf4678007dba824b269bc64329\", \"blink_unittests\": \"5dd5c27e8c5335d2a705302b88b3163290c95ede\", \"blink_web_tests\": \"0a131bf59b8349dc1e230763199bbd70c4826392\", \"boringssl_crypto_tests\": \"d7fc9f9280d2845a54caacb65a231cca9019a5f6\", \"boringssl_ssl_tests\": \"c0355406150d49049e18c5574ea08c0a75c02360\", \"browser_tests\": \"799b809141a364bdc1bdcb24cfe4b79b276bc9c2\", \"capture_unittests\": \"b47ac8b0208b0712869b06c1bf1e536f232aba16\", \"cast_unittests\": \"3368ec84a3050eee2ea75dd20674665de4c1451b\", \"cc_unittests\": \"9c92f81e9949c85fde6a1e7e454f9d4ae9500b69\", \"chrome_app_unittests\": \"bffb7741678a51e3c7b97d0a0323ee50f1973abe\", \"chromedriver_unittests\": \"55390f1f95200d58d8151909ff04932d4d943572\", \"components_browsertests\": \"226a31ceefe563ca0ec3ed29c9cd938cf545f260\", \"components_unittests\": \"b10521ca6fc34079e066b817986b5653b144765f\", \"compositor_unittests\": \"b13293b121ed60f46e6deb57f059855be06d9a5f\", \"content_browsertests\": \"251a622cf0ea08004736468370a3fdecb1fecb78\", \"content_shell_crash_test\": \"64440772190f4b1a3f8210b9de80d8f4349b02bc\", \"content_unittests\": \"d342bbf5e4b1a91c1dbb1df22e2d92a60ebdda1f\", \"crashpad_tests\": \"8c31f8a6b44fe3085d912e80cce3566c1ad5e2ab\", \"cronet_tests\": \"9a25ba7fd64bd1365f1a14984244da4813e01766\", \"cronet_unittests\": \"20dc35ae857086280dcf30d8c44b1521e51c4ffa\", \"crypto_unittests\": \"8afa4fc406d95674aab96db485a5f1f996e773b6\", \"dbus_unittests\": \"318b318a89787783d95ca90e9b15ef181a2fba41\", \"device_unittests\": \"5d59762bb0bd639c7b307ae2124eac9e94d5c6b5\", \"display_unittests\": \"8bb7776766c2f6e7ed0e5047f5f3c55ebc653dba\", \"events_unittests\": \"ba595dbf0f4eb77c08f45c424f19ef25d8c46dc0\", \"extensions_browsertests\": \"2b93a1c31f9ef32fdeaf11137534f2627385b6f5\", \"extensions_unittests\": \"d475780be4070b9247a493d64b55c7c9c056fe0d\", \"filesystem_service_unittests\": \"b0d1f2afc9cf1e3560bc4817cb37d2614cc6f16e\", \"flatbuffers_unittests\": \"abe7737f42a55ed672142d98ab3b938d8384c781\", \"gcm_unit_tests\": \"98ffd5bd3c9cbf0a476160a7a821a9a4ad093b8d\", \"gfx_unittests\": \"018f16383774b1c5fb4840bbe7b16809b5cb7979\", \"gin_unittests\": \"37a716b7d5a33e7d9b2004ec8420d25169bf901b\", \"google_apis_unittests\": \"b81bb3115ac7f703b93b4b9d0257da9d3e53e469\", \"gpu_unittests\": \"f9ff88edff365d320c5ae8238de1d77a4b1b07bc\", \"grit_python_unittests\": \"0532571aa381198443b89c1ec536d42cf612fc22\", \"gwp_asan_unittests\": \"6d2c16a07900b56a75ba6163313e33044b15ab93\", \"headless_browsertests\": \"6640bb002b7c7f203816f9c69aea2f54022cbf9c\", \"headless_unittests\": \"8c6d6561324c527166f3d9fa943dd2f820771b48\", \"interactive_ui_tests\": \"206720f86e6b528f4bbf478e3df74b176891275d\", \"ipc_tests\": \"bf1420290f1e8bab4eabbc58ccb58f91b8b42572\", \"jingle_unittests\": \"87160921af2564103c7df1bcc327be2f180bcf4d\", \"latency_unittests\": \"0282434574cd1ba9fe70fa39a4aece04ecafd1b7\", \"libjingle_xmpp_unittests\": \"56bd77fb465332173640ef9b0461474401e62f81\", \"liburlpattern_unittests\": \"a83f43b3cf59997d0cabfe4e594ba70836b4ca88\", \"media_blink_unittests\": \"11a14fc6e54912e5dee36c4ce049c12cfb91a2d8\", \"media_unittests\": \"48f652ce5dccd9edc2f2123f42d17bca2e60431d\", \"message_center_unittests\": \"15e033b286592566b0f3f5c26fc682cde222f5b2\", \"metrics_python_tests\": \"7b3d0253c4e35874b846f53991c7f3ac71164b40\", \"midi_unittests\": \"d2115ce2dc9bb06d4830d325372c507386664b53\", \"mojo_core_unittests\": \"f0a1f342770776e64be23066d3268deda8e1ccb4\", \"mojo_unittests\": \"7f1e749e54c37fcacb812dddbfdb95307dc5f0b6\", \"nacl_helper_nonsfi_unittests\": \"7052996918c3bbb6a5cbe45e86cd96629e379d43\", \"nacl_loader_unittests\": \"6bb6bcbb030a99df30f830628770433a1e667cc4\", \"native_theme_unittests\": \"716fcf3df19b486057ae30432d22567795761492\", \"net_unittests\": \"b5f82c22fdc091a49aea1d2179d49f2555392fa4\", \"openscreen_unittests\": \"35097dfb1fa370d41ba68a9444d1e5a844389fc6\", \"pdf_unittests\": \"01f141f9e51f73af21fbb4b2e7150bdbf8b6286f\", \"perfetto_unittests\": \"d4408f8e31c22b4bac18e457a315e5bb8a1ca08b\", \"ppapi_unittests\": \"6de9d063b10485fd45e4fda8579d41f9df8ad643\", \"printing_unittests\": \"d76f40fb62731b4c46683e5d15c84dcba1ec588a\", \"remoting_unittests\": \"efb33488f7ad680973fd8dfb6b97c8b40d51cac0\", \"sandbox_linux_unittests\": \"3830fcfaad11b305fec5140c3d734e83ddd5735c\", \"service_manager_unittests\": \"f5543eb1159c93382ae9df6ca61a380d678a63a4\", \"services_unittests\": \"5473c6a8dbb8ddec6b41af243c10e18562324ef3\", \"shell_dialogs_unittests\": \"54893793c490791c01f316bf79f50b6e31b97879\", \"skia_unittests\": \"2a9af14d5931b964f6947acbbbebfc3bb885d672\", \"snapshot_unittests\": \"2e33ffed4fcd49e5e6fac0e22079342eed846e1d\", \"sql_unittests\": \"0f6b271bcdf325e5b4db61680b1f01c12f73512a\", \"storage_unittests\": \"fbef935175d0a84d356b73b5d816333457a63c16\", \"sync_integration_tests\": \"1bde1f2fc11b9db713ae128049fa9d31c343d94c\", \"telemetry_gpu_unittests\": \"7592bd312b2889462705ed0bd3c7c4ed5d77810a\", \"telemetry_perf_unittests\": \"4784fc84a05fa0d9b77cb8c712a48539a9323919\", \"telemetry_unittests\": \"25e761733289dd6fa16708eb8c2759ced5d4e176\", \"traffic_annotation_auditor_unittests\": \"125f87cd5771f4d315c3b9a1347198c9f9259386\", \"ui_base_unittests\": \"6ce748bc031f431ce0438cf5b25d633a124ecaa6\", \"ui_touch_selection_unittests\": \"8c2e846709b59f71ef132b504e1b15a4ce4d701b\", \"unit_tests\": \"cfe713191129fe866717beeca4c89b1364a3c396\", \"url_unittests\": \"804dff8492406dafe9e3058884d700c3c804c99f\", \"views_perftests\": \"d70c9815d6c4c0c61a8e4b06c98599838b3ec44f\", \"views_unittests\": \"f3e340ce71a576efef64d73c30e46899227ed5a7\", \"viz_unittests\": \"80254bd3a379580dc58dd0aee3702728439624be\", \"vr_common_unittests\": \"d4eb1d00c625419240d7107f885bee55df8d79e2\", \"vr_pixeltests\": \"a96c1b76e806905b0a21fa837d04cd1202992c51\", \"webdriver_wpt_tests\": \"1cf0275a0058288c2a4a9b46446416d924e94870\", \"weblayer_browsertests\": \"834ac47ad7182bd375220fad9942b663fc228178\", \"weblayer_unittests\": \"39e4ebd9d85ed51cc5569f298250fbdb523f60ee\", \"wm_unittests\": \"62c986d63ebdaaef3b099ec3b91dc43afe5687ed\", \"wtf_unittests\": \"296a746e3e1d35ce0bd25dc887dbcf36f3a232f1\", \"x11_unittests\": \"13d5b9275030d976f26058fa0b971ddcd84ae1dc\", \"xr_browser_tests\": \"f9e4eac28d62598af32e71f7c8a5814b3577fd59\", \"zlib_unittests\": \"b0155f950686ef6773eb4f25967e3738bcdfaab2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_9ab1612fdb00db29a8d60b17a842600a8e29ecb37944be685dafbc3ddd01329a_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-72-rtao\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612232944765901",
"status": "COMPLETED",
"status_changed_ts": "1612235867520283",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180182",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5a003c087798edb7f74c2f6389328a0c45b66d5a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a003c087798edb7f74c2f6389328a0c45b66d5a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021875025418532624",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856444963345760176/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:517963aee3b34810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612235867548895",
"url": "https://ci.chromium.org/b/8856444963345760176",
"utcnow_ts": "1612235867971430"
}
}
[03:17:47.979] Invocation finished in 49m3.339731333s with status SUCCEEDED