[18:30:12.758] New invocation is queued and will start shortly
[18:30:13.941] Starting the invocation (attempt 1)
[18:30:13.986] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:30:13.986] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:30:13.986] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054248249031493984",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9cf6357e5caaac14af278a76679a86e424e76c42\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054248249031493984",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/9cf6357e5caaac14af278a76679a86e424e76c42",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9cf6357e5caaac14af278a76679a86e424e76c42",
"gitiles_ref:refs/heads/master"
]
}
[18:30:15.064] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581359414309100",
"id": "8888818186739378944",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9cf6357e5caaac14af278a76679a86e424e76c42\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581359414758545",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168461",
"builder:Linux Builder (dbg)",
"buildset:commit/git/9cf6357e5caaac14af278a76679a86e424e76c42",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9cf6357e5caaac14af278a76679a86e424e76c42",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054248249031493984",
"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/8888818186739378944/+/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": "1581359414758653",
"url": "https://ci.chromium.org/b/8888818186739378944",
"utcnow_ts": "1581359415057898"
}
}
[18:30:15.064] Task URL: https://ci.chromium.org/b/8888818186739378944
[18:30:15.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:2:0) after 1m0s
[18:31:15.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:2:0)
[18:31:15.059] Timer tick, asking Buildbucket for the build status
[18:31:15.248] Build 8888818186739378944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:31:15.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:3:0) after 1m0s
[18:32:15.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:3:0)
[18:32:15.237] Timer tick, asking Buildbucket for the build status
[18:32:16.139] Build 8888818186739378944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:32:16.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:4:0) after 1m0s
[18:33:16.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:4:0)
[18:33:16.194] Timer tick, asking Buildbucket for the build status
[18:33:16.334] Build 8888818186739378944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:33:16.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:5:0) after 1m0s
[18:34:16.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:5:0)
[18:34:16.394] Timer tick, asking Buildbucket for the build status
[18:34:16.573] Build 8888818186739378944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:34:16.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:6:0) after 1m0s
[18:35:16.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:6:0)
[18:35:16.603] Timer tick, asking Buildbucket for the build status
[18:35:16.731] Build 8888818186739378944: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:35:16.731] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:7:0) after 1m0s
[18:35:57.471] Received PubSub notification, asking Buildbucket for the build status
[18:35:57.589] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:16.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:7:0)
[18:36:16.783] Timer tick, asking Buildbucket for the build status
[18:36:16.933] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:16.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:9:0) after 1m0s
[18:37:16.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:9:0)
[18:37:16.938] Timer tick, asking Buildbucket for the build status
[18:37:17.109] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:17.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:10:0) after 1m0s
[18:38:17.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:10:0)
[18:38:17.085] Timer tick, asking Buildbucket for the build status
[18:38:18.178] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:18.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:11:0) after 1m0s
[18:39:18.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:11:0)
[18:39:18.161] Timer tick, asking Buildbucket for the build status
[18:39:19.458] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:19.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:12:0) after 1m0s
[18:40:19.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:12:0)
[18:40:19.511] Timer tick, asking Buildbucket for the build status
[18:40:19.699] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:19.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:13:0) after 1m0s
[18:41:19.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:13:0)
[18:41:19.697] Timer tick, asking Buildbucket for the build status
[18:41:19.886] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:19.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:14:0) after 1m0s
[18:42:19.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:14:0)
[18:42:19.920] Timer tick, asking Buildbucket for the build status
[18:42:20.113] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:20.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:15:0) after 1m0s
[18:43:20.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:15:0)
[18:43:20.117] Timer tick, asking Buildbucket for the build status
[18:43:20.558] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:20.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:16:0) after 1m0s
[18:44:20.689] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:16:0)
[18:44:20.689] Timer tick, asking Buildbucket for the build status
[18:44:21.729] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:21.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:17:0) after 1m0s
[18:45:21.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:17:0)
[18:45:21.754] Timer tick, asking Buildbucket for the build status
[18:45:22.082] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:22.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:18:0) after 1m0s
[18:46:22.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:18:0)
[18:46:22.111] Timer tick, asking Buildbucket for the build status
[18:46:26.633] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:26.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:19:0) after 1m0s
[18:47:26.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:19:0)
[18:47:26.869] Timer tick, asking Buildbucket for the build status
[18:47:27.303] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:27.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:20:0) after 1m0s
[18:48:27.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:20:0)
[18:48:27.434] Timer tick, asking Buildbucket for the build status
[18:48:27.666] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:27.666] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:21:0) after 1m0s
[18:49:27.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:21:0)
[18:49:27.727] Timer tick, asking Buildbucket for the build status
[18:49:27.939] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:27.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:22:0) after 1m0s
[18:50:27.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:22:0)
[18:50:27.995] Timer tick, asking Buildbucket for the build status
[18:50:28.815] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:28.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:23:0) after 1m0s
[18:51:28.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:23:0)
[18:51:28.903] Timer tick, asking Buildbucket for the build status
[18:51:29.088] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:29.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:24:0) after 1m0s
[18:52:29.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:24:0)
[18:52:29.121] Timer tick, asking Buildbucket for the build status
[18:52:29.565] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:29.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:25:0) after 1m0s
[18:53:29.657] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:25:0)
[18:53:29.657] Timer tick, asking Buildbucket for the build status
[18:53:29.854] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:29.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:26:0) after 1m0s
[18:54:29.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:26:0)
[18:54:29.960] Timer tick, asking Buildbucket for the build status
[18:54:30.166] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:30.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:27:0) after 1m0s
[18:55:30.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:27:0)
[18:55:30.238] Timer tick, asking Buildbucket for the build status
[18:55:30.394] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:30.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:28:0) after 1m0s
[18:56:30.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:28:0)
[18:56:30.452] Timer tick, asking Buildbucket for the build status
[18:56:30.609] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:30.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:29:0) after 1m0s
[18:57:30.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:29:0)
[18:57:30.721] Timer tick, asking Buildbucket for the build status
[18:57:31.500] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:31.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:30:0) after 1m0s
[18:58:31.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:30:0)
[18:58:31.554] Timer tick, asking Buildbucket for the build status
[18:58:31.956] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:31.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:31:0) after 1m0s
[18:59:32.022] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:31:0)
[18:59:32.022] Timer tick, asking Buildbucket for the build status
[18:59:32.434] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:32.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:32:0) after 1m0s
[19:00:32.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:32:0)
[19:00:32.452] Timer tick, asking Buildbucket for the build status
[19:00:32.895] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:32.895] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:33:0) after 1m0s
[19:01:32.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:33:0)
[19:01:32.939] Timer tick, asking Buildbucket for the build status
[19:01:33.129] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:33.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:34:0) after 1m0s
[19:02:33.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:34:0)
[19:02:33.184] Timer tick, asking Buildbucket for the build status
[19:02:33.373] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:33.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:35:0) after 1m0s
[19:03:33.689] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:35:0)
[19:03:33.689] Timer tick, asking Buildbucket for the build status
[19:03:33.801] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:33.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:36:0) after 1m0s
[19:04:34.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:36:0)
[19:04:34.128] Timer tick, asking Buildbucket for the build status
[19:04:34.316] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:34.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:37:0) after 1m0s
[19:05:34.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:37:0)
[19:05:34.550] Timer tick, asking Buildbucket for the build status
[19:05:34.918] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:34.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:38:0) after 1m0s
[19:06:35.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:38:0)
[19:06:35.189] Timer tick, asking Buildbucket for the build status
[19:06:35.726] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:35.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:39:0) after 1m0s
[19:07:35.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:39:0)
[19:07:35.911] Timer tick, asking Buildbucket for the build status
[19:07:36.319] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:36.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:40:0) after 1m0s
[19:08:36.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:40:0)
[19:08:36.480] Timer tick, asking Buildbucket for the build status
[19:08:36.748] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:36.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:41:0) after 1m0s
[19:09:37.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:41:0)
[19:09:37.019] Timer tick, asking Buildbucket for the build status
[19:09:37.278] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:37.278] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:42:0) after 1m0s
[19:10:37.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:42:0)
[19:10:37.556] Timer tick, asking Buildbucket for the build status
[19:10:37.699] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:37.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:43:0) after 1m0s
[19:11:38.096] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:43:0)
[19:11:38.096] Timer tick, asking Buildbucket for the build status
[19:11:38.428] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:38.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:44:0) after 1m0s
[19:12:38.699] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:44:0)
[19:12:38.699] Timer tick, asking Buildbucket for the build status
[19:12:38.881] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:38.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:45:0) after 1m0s
[19:13:39.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:45:0)
[19:13:39.121] Timer tick, asking Buildbucket for the build status
[19:13:39.598] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:46:0) after 1m0s
[19:14:39.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:46:0)
[19:14:39.692] Timer tick, asking Buildbucket for the build status
[19:14:39.874] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:39.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:47:0) after 1m0s
[19:15:39.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:47:0)
[19:15:39.859] Timer tick, asking Buildbucket for the build status
[19:15:40.192] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:40.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:48:0) after 1m0s
[19:16:40.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:48:0)
[19:16:40.229] Timer tick, asking Buildbucket for the build status
[19:16:40.750] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:40.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:49:0) after 1m0s
[19:17:40.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:49:0)
[19:17:40.780] Timer tick, asking Buildbucket for the build status
[19:17:41.094] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:41.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:50:0) after 1m0s
[19:18:41.269] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:50:0)
[19:18:41.269] Timer tick, asking Buildbucket for the build status
[19:18:41.581] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:41.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:51:0) after 1m0s
[19:19:41.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:51:0)
[19:19:41.927] Timer tick, asking Buildbucket for the build status
[19:19:43.254] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:43.254] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:52:0) after 1m0s
[19:20:43.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:52:0)
[19:20:43.283] Timer tick, asking Buildbucket for the build status
[19:20:43.495] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:43.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:53:0) after 1m0s
[19:21:43.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:53:0)
[19:21:43.523] Timer tick, asking Buildbucket for the build status
[19:21:43.816] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:43.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:54:0) after 1m0s
[19:22:43.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:54:0)
[19:22:43.846] Timer tick, asking Buildbucket for the build status
[19:22:44.233] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:44.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:55:0) after 1m0s
[19:23:44.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:55:0)
[19:23:44.278] Timer tick, asking Buildbucket for the build status
[19:23:44.496] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:44.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:56:0) after 1m0s
[19:24:44.689] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:56:0)
[19:24:44.689] Timer tick, asking Buildbucket for the build status
[19:24:44.880] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:44.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:57:0) after 1m0s
[19:25:45.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:57:0)
[19:25:45.061] Timer tick, asking Buildbucket for the build status
[19:25:45.359] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:45.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:58:0) after 1m0s
[19:26:45.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:58:0)
[19:26:45.432] Timer tick, asking Buildbucket for the build status
[19:26:45.870] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:45.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:59:0) after 1m0s
[19:27:45.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:59:0)
[19:27:45.967] Timer tick, asking Buildbucket for the build status
[19:27:46.173] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:46.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:60:0) after 1m0s
[19:28:46.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:60:0)
[19:28:46.228] Timer tick, asking Buildbucket for the build status
[19:28:46.382] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:46.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:61:0) after 1m0s
[19:29:46.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:61:0)
[19:29:46.435] Timer tick, asking Buildbucket for the build status
[19:29:46.734] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:46.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:62:0) after 1m0s
[19:30:46.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:62:0)
[19:30:46.933] Timer tick, asking Buildbucket for the build status
[19:30:47.099] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:47.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:63:0) after 1m0s
[19:31:47.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:63:0)
[19:31:47.190] Timer tick, asking Buildbucket for the build status
[19:31:47.546] Build 8888818186739378944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:47.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054248249031493984:64:0) after 1m0s
[19:32:33.217] Received PubSub notification, asking Buildbucket for the build status
[19:32:33.369] Build 8888818186739378944: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:32:33.369] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581363151770958",
"created_by": "project:chromium",
"created_ts": "1581359414309100",
"id": "8888818186739378944",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9cf6357e5caaac14af278a76679a86e424e76c42\"}}",
"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\"}, \"$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-east1-d-8-82-wlzx\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168461, \"got_angle_revision\": \"fc1e8503d88b8cfaa35610c382f0265be163f54a\", \"got_dawn_revision\": \"fb01a6024e4d8ba45047baa410783f7774e6cd9e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"9cf6357e5caaac14af278a76679a86e424e76c42\", \"got_revision_cp\": \"refs/heads/master@{#739942}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"b3b2a4fc756f8f6dca980c75d785e06134b9d5f8\", \"got_v8_revision\": \"d0da7b4db4d335d91a374ec3be53199c482e7174\", \"got_v8_revision_cp\": \"refs/heads/8.2.56@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9cf6357e5caaac14af278a76679a86e424e76c42\", \"swarm_hashes_refs/heads/master(at){#739942}_without_patch\": {\"accessibility_unittests\": \"3c03ef22e10de5565e1c20b860b2aa429e7b14d5\", \"angle_unittests\": \"9bed93b3ff097bf3354a9386be8318a6b2c771f9\", \"app_shell_unittests\": \"3fb01c2308a7d9aaad90b6e08d1a95800acd7821\", \"aura_unittests\": \"6187ce5eda692e22de1e65dc455be54da0097ec3\", \"base_unittests\": \"b4a25a59f6260d9bfe532fc60d79dc8a2774fcc5\", \"base_util_unittests\": \"39dc6513396b0eaa1e1c762ae38e9df8602b2578\", \"blink_common_unittests\": \"03e5e40f5274c85f4db6fcef136aa8d2ed341ef5\", \"blink_fuzzer_unittests\": \"32c2d8852b6b92017ac1502c0cf5d568717db795\", \"blink_heap_unittests\": \"ab4057fd9240d9cd591b1b8f4bdf4054011681d7\", \"blink_platform_unittests\": \"d2fe0a89debb87feff94e5beb0a5224e5d3bb315\", \"blink_python_tests\": \"5556357194f89c7159f179c25dd49b29a3abd716\", \"blink_unittests\": \"1cfc503b99f2a5a0e5aff03509c571389dd5c0e9\", \"blink_web_tests\": \"48e55b5cf0bcef89bad8ae59d5914a4be25a84cb\", \"boringssl_crypto_tests\": \"b1df0a2d21f8b4d3ed8df59b18bb31e2aa536ea6\", \"boringssl_ssl_tests\": \"57845d3f63f48767eea1681b056c279b4f61e347\", \"browser_tests\": \"e9fda2cbabe3d597a1992b3f02cc8502dd115556\", \"cacheinvalidation_unittests\": \"cd2b707d95251cd03159610ca3b6bdd7f8aa7337\", \"capture_unittests\": \"21a2934dfce200638df1cd80f33b98739aebaed0\", \"cast_unittests\": \"bbf2b2e1ee4963fa4b6ff7f6ef1de9c14494eac9\", \"cc_unittests\": \"c42c06fb199cc68684188069f9518b0dcbc33498\", \"chrome_app_unittests\": \"5f2cd33c3972544b913bd0147729fc1edf7dfa6e\", \"chromedriver_unittests\": \"08b63df361fce34b7ba2e2e34a08d11c7cf92b52\", \"components_browsertests\": \"0b34252316c84d907621ab59b6595d7551fa4ff3\", \"components_unittests\": \"4e0c6f508887dccb253410236356d824551237db\", \"compositor_unittests\": \"432c0d22aeaa760c93b45f29807a787ba28da6ef\", \"content_browsertests\": \"4edeb0c71ebda6d3a911a87e15b3e45361d2b2c1\", \"content_shell_crash_test\": \"dd7cc035d57d95d5a03db53a12ce1feca2079622\", \"content_unittests\": \"6b6fdac97cc53e071803beea1278e73566277b10\", \"crashpad_tests\": \"62b9398fe67834e0b6dec8c8a303821e2c605b28\", \"cronet_tests\": \"586dd00ead461c3d655af44ddb94e442b3d23bc2\", \"cronet_unittests\": \"ef05fa2c1cc2e44d1f65f6a06356577ccb297b01\", \"crypto_unittests\": \"09d04f9b9f7973d56a189b43d307759aff4269bf\", \"dbus_unittests\": \"fe55544f15cc914f9c93187578e9420cf8f18053\", \"device_unittests\": \"c09e02d37397fb94ef805019397ecc9ab7a74ce2\", \"display_unittests\": \"ac6633edf2ffc7369282f287afa4591b858d6689\", \"events_unittests\": \"3bebfe7f84b77d86b4a642ab12a59116791a8f8f\", \"extensions_browsertests\": \"7507ca898467befcdbfea456ebad52f22929df6f\", \"extensions_unittests\": \"221a3638d1122b6609e3938d70bd1c79772705ad\", \"filesystem_service_unittests\": \"d8750ceff7df048a0cd50114d2f2e4d480906e71\", \"flatbuffers_unittests\": \"e0b6595529551438a032f600cb9a377756ae2cbe\", \"gcm_unit_tests\": \"428784f725b33153d8ae6852bd36261ad79f0da2\", \"gfx_unittests\": \"7eb02154becb8c3df7b3f7492bd4a1627d750080\", \"gin_unittests\": \"ce1b8cf0cce870cd798a34bcd3fec571ad56dc99\", \"google_apis_unittests\": \"c1014e35c5e19133f568e0e604867fd18cbc5fdd\", \"gpu_unittests\": \"45818f31465246ca8f2549ba0388a7d56222ffb1\", \"grit_python_unittests\": \"ce1bc9b2b1828ea6d70230d85827604381bdc55d\", \"gwp_asan_unittests\": \"6e69123770a5070445c8c32f36cae4b77f34a9a0\", \"headless_browsertests\": \"74fa7ef6ce7712e815b4ad77cf767508f995d0df\", \"headless_unittests\": \"49fd4ef8a9fc84d3d9ca01d6255a7b4fbbe6a261\", \"interactive_ui_tests\": \"06519ffd51aab090eca58c28a778646be6e2698e\", \"ipc_tests\": \"fe860dc50ce2f52aafecbd37be3af53056f69666\", \"jingle_unittests\": \"1bb854753bfbdf72a487f0d086da811a27cc54e2\", \"latency_unittests\": \"1c8898487cac6879fd4aa2d5109578551176184f\", \"libjingle_xmpp_unittests\": \"4c7fbaf8214558e9f7101974abe3ee0ee8426df2\", \"media_blink_unittests\": \"56e758620f1a568f1befde039127345004336a97\", \"media_service_unittests\": \"23c7e71ca311970e6f3ad5ef637c0be3a0fe919e\", \"media_unittests\": \"a60fa7e44d989a572f8678bf136d445dd493c68f\", \"message_center_unittests\": \"559e345f2f95ec7b8549197646d28e7c3ce425d3\", \"metrics_python_tests\": \"52f0088cc000401062f76b77069044a800c15469\", \"midi_unittests\": \"3364f0ab137283e2f45a71a67910d98246e66795\", \"mojo_core_unittests\": \"0ff015e8dbd61f3cf087fce35b99d5aa4aff4a7f\", \"mojo_unittests\": \"e0fa8134ee223908b32f459818d2a86d8d0547cf\", \"nacl_helper_nonsfi_unittests\": \"d23218cf729a5d58e1b70bd7135c65af93203f95\", \"nacl_loader_unittests\": \"ea65546f2d1abf590b6c0a8ff0db0cb3514d309f\", \"native_theme_unittests\": \"4514441037f78212a6e72fcfaeb9da91c8e3856f\", \"net_unittests\": \"55d1908a6e6e1bc565d08596d7a07abc7bc2a34b\", \"pdf_unittests\": \"c3f7d17a3d77ed07d1e867f69f19a0f3c30840da\", \"perfetto_unittests\": \"20dcbab78735d93ba0e17b5bb793729eefff0dc6\", \"ppapi_unittests\": \"a558fa15158d4a72a94549bd815e1678b2f938de\", \"printing_unittests\": \"cc4f9b867c4f4b1a6c2c6622af17070e33a4b490\", \"remoting_unittests\": \"8eacc0da67988a940660973a1573d711f8b0cd03\", \"sandbox_linux_unittests\": \"27addec9ba85f84b5b272ebc77d76854d6a042f3\", \"service_manager_unittests\": \"ccc722f82af50816dfcf6fa19b8ebde3e0019520\", \"services_unittests\": \"9740c0f7c93f8805118c6298e66ddb08dcec399e\", \"shell_dialogs_unittests\": \"8daa72f38144189add103896d4006a7c6729e772\", \"skia_unittests\": \"9bf5aab9606451c7f1825eb386487313e5794256\", \"snapshot_unittests\": \"f290ad814ce768866f4f56fb8572bdc41558ecd8\", \"sql_unittests\": \"8bab093962a7af1ea55e0bfd1e7d0f8236e6cc3d\", \"storage_unittests\": \"80a210a44c9e7617816b16ae786d3849129a04ad\", \"sync_integration_tests\": \"fb62805446c131a5ed86a96420a02a0004a374fe\", \"telemetry_gpu_unittests\": \"74ed7502526008ada2f15a065e680b5c484348fe\", \"telemetry_perf_unittests\": \"e0b301af590ba8aa80d62ed3e1b85df0d41b6811\", \"telemetry_unittests\": \"2ef02f1bfd2860bc6eeeb98445c9b98cff41097d\", \"traffic_annotation_auditor_unittests\": \"4ec36cc891d9b5d81cdeee70d5ba503d90682294\", \"ui_base_unittests\": \"ff06795a3d599861e7ce5940e3ae7eaf7f989d14\", \"ui_touch_selection_unittests\": \"c9b4f1601582dd4e39948e29e88d07e888a3236b\", \"unit_tests\": \"04407078bd0eefa2199937c146ef8ced9cc6addf\", \"url_unittests\": \"86f3b6098dd3de3c782c788d431b5539046919c5\", \"views_perftests\": \"544c781dd13f4d157459d35b9980986b9c86964a\", \"views_unittests\": \"bcf01855b1510271c969565973a3877635fccb59\", \"viz_unittests\": \"60f99a1c1ce9f09fef32d6ebdb069c60a9412399\", \"vr_common_unittests\": \"ef5a08a4ba6ab264748d1a37fdf343b824016670\", \"vr_pixeltests\": \"ac602f843606fa5721e2225bafe31f908f2b4b0b\", \"webdriver_wpt_tests\": \"8238f3f62fc319e131812d095ff3b59f456c1f1b\", \"weblayer_browsertests\": \"3b1766cc66ec8d1a60eeafe57c18b9162d31c960\", \"wm_unittests\": \"6da6ae6c788c8a6be863054a6ee508a7d7249cda\", \"wtf_unittests\": \"a4162f678e9cea8e41990e72a5d3bc8e608311f8\", \"xr_browser_tests\": \"fdbe90defec8c8a3379df71c3c279bc5fbb04be7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_b91573fd5c97bdd1870d9c2b7f3dee6525147840030fe1e0975f137bcc8950b1_v2\", \"builder_bf333895421fefa78318d8049b60faf5c230ceed823f417948b15e1b2d972d6c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-82-wlzx\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581359756678769",
"status": "COMPLETED",
"status_changed_ts": "1581363152101478",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168461",
"builder:Linux Builder (dbg)",
"buildset:commit/git/9cf6357e5caaac14af278a76679a86e424e76c42",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9cf6357e5caaac14af278a76679a86e424e76c42",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054248249031493984",
"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/8888818186739378944/+/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:4a492f4c1bbd2510",
"user_agent:luci-scheduler"
],
"updated_ts": "1581363152109671",
"url": "https://ci.chromium.org/b/8888818186739378944",
"utcnow_ts": "1581363153359924"
}
}
[19:32:33.369] Invocation finished in 1h2m20.628686508s with status SUCCEEDED