[06:36:54.328] New invocation is queued and will start shortly
[06:36:55.585] Starting the invocation (attempt 1)
[06:36:55.621] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:36:55.621] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:36:55.621] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026751648650000704",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"97f817cc2f671bd1efb05e919b1220798b08332f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9026751648650000704",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/97f817cc2f671bd1efb05e919b1220798b08332f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97f817cc2f671bd1efb05e919b1220798b08332f",
"gitiles_ref:refs/heads/master"
]
}
[06:36:56.409] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607582215819360",
"id": "8861321586424145232",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"97f817cc2f671bd1efb05e919b1220798b08332f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607582216291801",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/178520",
"builder:Linux Builder (dbg)",
"buildset:commit/git/97f817cc2f671bd1efb05e919b1220798b08332f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97f817cc2f671bd1efb05e919b1220798b08332f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026751648650000704",
"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/8861321586424145232/+/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": "1607582216291948",
"url": "https://ci.chromium.org/b/8861321586424145232",
"utcnow_ts": "1607582216402112"
}
}
[06:36:56.410] Task URL: https://ci.chromium.org/b/8861321586424145232
[06:36:56.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:2:0) after 1m0s
[06:37:56.430] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:2:0)
[06:37:56.430] Timer tick, asking Buildbucket for the build status
[06:37:56.820] Build 8861321586424145232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:37:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:3:0) after 1m0s
[06:38:56.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:3:0)
[06:38:56.823] Timer tick, asking Buildbucket for the build status
[06:38:57.212] Build 8861321586424145232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:38:57.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:4:0) after 1m0s
[06:39:57.221] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:4:0)
[06:39:57.221] Timer tick, asking Buildbucket for the build status
[06:39:57.296] Build 8861321586424145232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:39:57.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:5:0) after 1m0s
[06:40:57.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:5:0)
[06:40:57.313] Timer tick, asking Buildbucket for the build status
[06:40:57.381] Build 8861321586424145232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:40:57.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:6:0) after 1m0s
[06:41:57.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:6:0)
[06:41:57.354] Timer tick, asking Buildbucket for the build status
[06:41:57.415] Build 8861321586424145232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:41:57.415] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:7:0) after 1m0s
[06:41:59.036] Received PubSub notification, asking Buildbucket for the build status
[06:41:59.334] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:57.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:7:0)
[06:42:57.433] Timer tick, asking Buildbucket for the build status
[06:42:57.574] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:57.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:9:0) after 1m0s
[06:43:57.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:9:0)
[06:43:57.701] Timer tick, asking Buildbucket for the build status
[06:43:57.762] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:57.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:10:0) after 1m0s
[06:44:57.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:10:0)
[06:44:57.745] Timer tick, asking Buildbucket for the build status
[06:44:57.806] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:57.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:11:0) after 1m0s
[06:45:57.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:11:0)
[06:45:57.879] Timer tick, asking Buildbucket for the build status
[06:45:57.933] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:57.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:12:0) after 1m0s
[06:46:57.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:12:0)
[06:46:57.933] Timer tick, asking Buildbucket for the build status
[06:46:58.042] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:58.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:13:0) after 1m0s
[06:47:58.076] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:13:0)
[06:47:58.076] Timer tick, asking Buildbucket for the build status
[06:47:58.184] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:58.184] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:14:0) after 1m0s
[06:48:58.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:14:0)
[06:48:58.233] Timer tick, asking Buildbucket for the build status
[06:48:58.320] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:58.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:15:0) after 1m0s
[06:49:58.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:15:0)
[06:49:58.338] Timer tick, asking Buildbucket for the build status
[06:49:58.466] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:58.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:16:0) after 1m0s
[06:50:59.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:16:0)
[06:50:59.646] Timer tick, asking Buildbucket for the build status
[06:50:59.717] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:59.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:17:0) after 1m0s
[06:51:59.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:17:0)
[06:51:59.933] Timer tick, asking Buildbucket for the build status
[06:52:00.000] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:00.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:18:0) after 1m0s
[06:53:00.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:18:0)
[06:53:00.253] Timer tick, asking Buildbucket for the build status
[06:53:00.352] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:00.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:19:0) after 1m0s
[06:54:00.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:19:0)
[06:54:00.398] Timer tick, asking Buildbucket for the build status
[06:54:00.509] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:00.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:20:0) after 1m0s
[06:55:00.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:20:0)
[06:55:00.627] Timer tick, asking Buildbucket for the build status
[06:55:04.046] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:04.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:21:0) after 1m0s
[06:56:04.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:21:0)
[06:56:04.255] Timer tick, asking Buildbucket for the build status
[06:56:04.325] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:04.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:22:0) after 1m0s
[06:57:04.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:22:0)
[06:57:04.402] Timer tick, asking Buildbucket for the build status
[06:57:04.723] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:04.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:23:0) after 1m0s
[06:58:04.842] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:23:0)
[06:58:04.842] Timer tick, asking Buildbucket for the build status
[06:58:04.911] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:04.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:24:0) after 1m0s
[06:59:05.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:24:0)
[06:59:05.069] Timer tick, asking Buildbucket for the build status
[06:59:05.401] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:05.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:25:0) after 1m0s
[07:00:05.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:25:0)
[07:00:05.604] Timer tick, asking Buildbucket for the build status
[07:00:05.795] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:05.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:26:0) after 1m0s
[07:01:07.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:26:0)
[07:01:07.057] Timer tick, asking Buildbucket for the build status
[07:01:07.162] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:07.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:27:0) after 1m0s
[07:02:07.344] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:27:0)
[07:02:07.344] Timer tick, asking Buildbucket for the build status
[07:02:07.447] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:07.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:28:0) after 1m0s
[07:03:07.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:28:0)
[07:03:07.487] Timer tick, asking Buildbucket for the build status
[07:03:07.648] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:07.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:29:0) after 1m0s
[07:04:07.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:29:0)
[07:04:07.696] Timer tick, asking Buildbucket for the build status
[07:04:07.832] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:07.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:30:0) after 1m0s
[07:05:07.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:30:0)
[07:05:07.889] Timer tick, asking Buildbucket for the build status
[07:05:08.240] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:08.240] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:31:0) after 1m0s
[07:06:08.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:31:0)
[07:06:08.320] Timer tick, asking Buildbucket for the build status
[07:06:08.405] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:08.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:32:0) after 1m0s
[07:07:08.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:32:0)
[07:07:08.433] Timer tick, asking Buildbucket for the build status
[07:07:08.832] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:08.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:33:0) after 1m0s
[07:08:08.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:33:0)
[07:08:08.900] Timer tick, asking Buildbucket for the build status
[07:08:08.958] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:08.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:34:0) after 1m0s
[07:09:08.947] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:34:0)
[07:09:08.947] Timer tick, asking Buildbucket for the build status
[07:09:09.028] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:09.028] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:35:0) after 1m0s
[07:10:09.081] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:35:0)
[07:10:09.081] Timer tick, asking Buildbucket for the build status
[07:10:09.141] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:09.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:36:0) after 1m0s
[07:11:09.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:36:0)
[07:11:09.194] Timer tick, asking Buildbucket for the build status
[07:11:09.377] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:09.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:37:0) after 1m0s
[07:12:09.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:37:0)
[07:12:09.401] Timer tick, asking Buildbucket for the build status
[07:12:09.497] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:09.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:38:0) after 1m0s
[07:13:09.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:38:0)
[07:13:09.552] Timer tick, asking Buildbucket for the build status
[07:13:09.729] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:09.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:39:0) after 1m0s
[07:14:09.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:39:0)
[07:14:09.808] Timer tick, asking Buildbucket for the build status
[07:14:09.870] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:09.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:40:0) after 1m0s
[07:15:09.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:40:0)
[07:15:09.932] Timer tick, asking Buildbucket for the build status
[07:15:13.603] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:13.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:41:0) after 1m0s
[07:16:13.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:41:0)
[07:16:13.655] Timer tick, asking Buildbucket for the build status
[07:16:13.982] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:13.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:42:0) after 1m0s
[07:17:14.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:42:0)
[07:17:14.024] Timer tick, asking Buildbucket for the build status
[07:17:14.125] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:14.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:43:0) after 1m0s
[07:18:14.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:43:0)
[07:18:14.151] Timer tick, asking Buildbucket for the build status
[07:18:14.217] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:14.217] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:44:0) after 1m0s
[07:19:14.267] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:44:0)
[07:19:14.267] Timer tick, asking Buildbucket for the build status
[07:19:14.378] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:14.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:45:0) after 1m0s
[07:20:14.456] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:45:0)
[07:20:14.456] Timer tick, asking Buildbucket for the build status
[07:20:14.552] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:14.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:46:0) after 1m0s
[07:21:14.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:46:0)
[07:21:14.609] Timer tick, asking Buildbucket for the build status
[07:21:14.667] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:14.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:47:0) after 1m0s
[07:22:14.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:47:0)
[07:22:14.720] Timer tick, asking Buildbucket for the build status
[07:22:14.832] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:14.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:48:0) after 1m0s
[07:23:14.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:48:0)
[07:23:14.880] Timer tick, asking Buildbucket for the build status
[07:23:14.929] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:49:0) after 1m0s
[07:24:14.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:49:0)
[07:24:14.996] Timer tick, asking Buildbucket for the build status
[07:24:15.062] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:15.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:50:0) after 1m0s
[07:25:15.153] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:50:0)
[07:25:15.153] Timer tick, asking Buildbucket for the build status
[07:25:15.207] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:15.207] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:51:0) after 1m0s
[07:26:15.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:51:0)
[07:26:15.253] Timer tick, asking Buildbucket for the build status
[07:26:15.377] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:15.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:52:0) after 1m0s
[07:27:15.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:52:0)
[07:27:15.443] Timer tick, asking Buildbucket for the build status
[07:27:15.771] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:15.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:53:0) after 1m0s
[07:28:15.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:53:0)
[07:28:15.856] Timer tick, asking Buildbucket for the build status
[07:28:15.923] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:15.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:54:0) after 1m0s
[07:29:15.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:54:0)
[07:29:15.958] Timer tick, asking Buildbucket for the build status
[07:29:16.048] Build 8861321586424145232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:16.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9026751648650000704:55:0) after 1m0s
[07:29:19.456] Received PubSub notification, asking Buildbucket for the build status
[07:29:19.576] Build 8861321586424145232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:29:19.576] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607585358537232",
"created_by": "project:chromium",
"created_ts": "1607582215819360",
"id": "8861321586424145232",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"97f817cc2f671bd1efb05e919b1220798b08332f\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"97f817cc2f671bd1efb05e919b1220798b08332f\", \"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-199-zhcu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 178520, \"got_angle_revision\": \"067a12f059511ead6e91ab9e91af1d180ad9c144\", \"got_dawn_revision\": \"29fd62989338a0d5812bc654a3afee6dc684bfc4\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"97f817cc2f671bd1efb05e919b1220798b08332f\", \"got_revision_cp\": \"refs/heads/master@{#835567}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"d94a77b304f4fcb503ea037be3e5d7f2a371c4b6\", \"got_v8_revision\": \"7e546be6b2f61eb429d51abb78e973f7f623f62f\", \"got_v8_revision_cp\": \"refs/heads/8.9.126@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"97f817cc2f671bd1efb05e919b1220798b08332f\", \"swarm_hashes_refs/heads/master(at){#835567}_without_patch\": {\"absl_hardening_tests\": \"ce6ea96c78155f080168c16c2681d862384279e6\", \"accessibility_unittests\": \"27994c8ed2183e7559b61e5895ed001600ca0a0e\", \"angle_unittests\": \"a2f729072e4665b5a585be1e2df834560dbf6603\", \"app_shell_unittests\": \"a141b65711f7eb01b8d2220c43f017e0b5150e7f\", \"aura_unittests\": \"22378d0d737e199843491ab3927dd178d30c0660\", \"base_unittests\": \"4e9fcf1517b2931efb70c424f32bb517f72922d2\", \"base_util_unittests\": \"81ecf72c57d974cc56d83b7b0ff77a85c89760fc\", \"blink_common_unittests\": \"c3edcf8aba37b05da7faee72f1e801742ed8bc5b\", \"blink_fuzzer_unittests\": \"8c2244236363930436430897158b4c63aacfa8d6\", \"blink_heap_unittests\": \"3045221d24da023c6415495576c7c8ddadfa98b3\", \"blink_platform_unittests\": \"ec6de3bc699397861af859ba6cdaf315557e99f4\", \"blink_python_tests\": \"9c932ed135b68edbd16bb1a3db6d057d4a5a677b\", \"blink_unittests\": \"2e64994e973f671d1d13f166a466a841a927afd6\", \"blink_web_tests\": \"c1b12336e0b7a7ed62a8ddae3178aa601a23b4ca\", \"boringssl_crypto_tests\": \"d88a4daa1e294d622d73b4d828b22ccf2691c868\", \"boringssl_ssl_tests\": \"708c14fd9d52b321e3f3b431e8acddd0eb86f3ab\", \"browser_tests\": \"28008c62a79962a3b140917baf8295ac4802a42c\", \"capture_unittests\": \"64fe1347f6b2ce6cc299766c2704a5f70769e5bf\", \"cast_unittests\": \"0a9ec0300f2e50a99fda24ab4884af5ab495b2ea\", \"cc_unittests\": \"deae0f8d76dbdc1184dedb80737f4600f32a10f2\", \"chrome_app_unittests\": \"3117d2f254312dd8d5d7a8c5150e5365bf65ebc0\", \"chromedriver_unittests\": \"bef8522abcf4e1a347c3074d7bc148ab73a239ba\", \"components_browsertests\": \"fbc5c1637e056f8ee4e955490955beca3a29c606\", \"components_unittests\": \"730868206bd7b916020c85be83c43dacb9830fae\", \"compositor_unittests\": \"8bfffb95a134dbdf639dc946aa27fc285e28e9ff\", \"content_browsertests\": \"026cfff4dc2498129b859aa88724d20eea8f8266\", \"content_shell_crash_test\": \"1e07186074c7b2aa2a8a8264861e69564aca93e0\", \"content_unittests\": \"c677874b515d8e14d0f73f8bdc28513270e9ea68\", \"crashpad_tests\": \"3511c3ae1239f18db601c238068ac7fa8384b644\", \"cronet_tests\": \"5140bf5eb1740139673247ec0db73ddf48161c8d\", \"cronet_unittests\": \"36f93dd2b6ba571dbb3e9547f5b8d84b36721506\", \"crypto_unittests\": \"1362e1e3e40a579d1905843f2316c959f385e6e8\", \"dbus_unittests\": \"b44922f5f70808f1135623f74ae0101c588d5233\", \"device_unittests\": \"27e0fe295e20acb4e83680e55d3d1daa739ab066\", \"display_unittests\": \"0cdb3f84a5f8de76e1b988d17a95e11929c731e7\", \"events_unittests\": \"45d29f99e107c53797f7d49ed35b79f2e8259cbe\", \"extension_docserver_python_unittests\": \"4baa686be36c645bbbfb36ced653a8e3d2672083\", \"extensions_browsertests\": \"50fa7be459ac069af40d76cb6dd4823e494bc1f4\", \"extensions_unittests\": \"56e4a14eb4c56dfcd805f26b60ff43b58c142cbc\", \"filesystem_service_unittests\": \"5d00d97856b1438baf84b6b8a34dd651d363a36b\", \"flatbuffers_unittests\": \"4d7f6411689e0ca026ebaa10a0eb906a6c506cdd\", \"gcm_unit_tests\": \"9b30c55132abe83746ce5d4a1ebd8581ab8c8d29\", \"gfx_unittests\": \"b51553bf61741e23d09cc0078a1ec24fb87a1151\", \"gin_unittests\": \"6908289aaef373b1c36a30cd65482fb299f2f7c7\", \"google_apis_unittests\": \"01c7a89520cf3eba89979ea4ca8766b4a0116a04\", \"gpu_unittests\": \"2355c297d4604f20aab6cab9e3e7b9521698640f\", \"grit_python_unittests\": \"4240fd59c31e9646d2ab4fe1082f5d79a3314641\", \"gwp_asan_unittests\": \"ead9e9cd9af168e1b9b39eb8c0760d79a6ce26a4\", \"headless_browsertests\": \"9d3d5a26db8de92e483ce9a1c7ab837daff17e94\", \"headless_unittests\": \"e69e70de906a7070e9d76cd9a412a1c8476ea2ac\", \"interactive_ui_tests\": \"49eaa4619c9f2e8fba5693aa63dbd7d7795a70cb\", \"ipc_tests\": \"b56d149865d7813a908566d182d94732b6ac51b9\", \"jingle_unittests\": \"83d5bbb9a03738919445caa57fa283ea0efc1018\", \"latency_unittests\": \"ed2b893d789348e517b6600367ee410e26c71638\", \"libjingle_xmpp_unittests\": \"16558becf9907b263f9d0383670a5693750aa6ea\", \"liburlpattern_unittests\": \"a1430572ac943420ea8a3a98e3d27a3f7df0d7b5\", \"media_blink_unittests\": \"458aa7fffa8087b4e5ed0347b5d2e93248dc08e1\", \"media_unittests\": \"a3aee9cc7424ba947babd6050bb4a636f6a9f63c\", \"message_center_unittests\": \"aa1156d276786335f0c4d880d86b7b15b299dcb5\", \"metrics_python_tests\": \"c6c36999a2afe472991f526551de5d907db711a6\", \"midi_unittests\": \"f142e47df3d3d4391a7c33cee29d06196a1978da\", \"mojo_core_unittests\": \"2e26fd67fd8bfe2daef4716681efa9ca27feb38a\", \"mojo_unittests\": \"8848e7c8fc2fe27a7b58839cdda99cb4d0fe3dee\", \"nacl_helper_nonsfi_unittests\": \"0923ba313646bbd8fca35aa9efb58f24a547a248\", \"nacl_loader_unittests\": \"c271dbc0a3dcf07f68bf8901c47e4c4a4d2fefd2\", \"native_theme_unittests\": \"331f73fd5c98a792a4c682efc1bb5cee575e6896\", \"net_unittests\": \"e39a7e5be6576491a3af9135afb02d36a59d6e59\", \"openscreen_unittests\": \"a474191124aad45f94529d197e358a5277c5dd8b\", \"pdf_unittests\": \"5583d5040075d7a0fc9451f5a9c794383c5a9ba4\", \"perfetto_unittests\": \"905e805c8ff222aaddd49f6a0a519937eadda2ae\", \"ppapi_unittests\": \"9c8f87c4449f332d0630fcf860c0a90197e9cff1\", \"printing_unittests\": \"f62dc10550e98024c0ca08df3ba46a240ce02671\", \"remoting_unittests\": \"3ebc127b5028cac142a94b08caec85d6daf53f11\", \"sandbox_linux_unittests\": \"1a4c912ee244e606da5bf9ff676661f406ab7ec9\", \"service_manager_unittests\": \"ac4851b26a7c42769f71ec2ef01a380cf87acfbd\", \"services_unittests\": \"f0fdb83bde0eacabced4386385d2cf64ae6ca161\", \"shell_dialogs_unittests\": \"0bfc1e6fb33c58a637c867c4bdc5e563b4b18f68\", \"skia_unittests\": \"817e60990260f45ed4cf79bdcd4b1c8d4c5c2eb2\", \"snapshot_unittests\": \"45c46d98fec33fd2934ceec90add38b72b79acc8\", \"sql_unittests\": \"e9981bd53c474b1f9977608196d8bfd004299e88\", \"storage_unittests\": \"7cde301e337c93195173e1a68f2427ab1af5d429\", \"sync_integration_tests\": \"49894a8ae604b66836f9736d725bb288cde74fb2\", \"telemetry_gpu_unittests\": \"be8b0f31f9010b1024033e165136f46540f359d6\", \"telemetry_perf_unittests\": \"dc1c3602918f7d9c07b58c57bf2242f6b89ff5e7\", \"telemetry_unittests\": \"0ad98aa0a7866e686b1c4935a89a0a84b83ebf62\", \"traffic_annotation_auditor_unittests\": \"433fe53afa44c850db957c04e08538f7de05842a\", \"ui_base_unittests\": \"378339b488674be4ae1b09fade90bfd837578c80\", \"ui_touch_selection_unittests\": \"92309400d21a747f953fa64f7320820d90da68d7\", \"unit_tests\": \"6fa57d8f3868f1c663d110348e4a9a002aec721c\", \"url_unittests\": \"87d16c460197f833457667498da8dcf54a6c0497\", \"views_perftests\": \"88dc503b315aa72a188ac4a152f53fb7d6a03037\", \"views_unittests\": \"ab148efe9d8bfaf3a5bcc260cd1eec4f98413f14\", \"viz_unittests\": \"7552eb56d322f06e48bca59ab33da06dd6be2a6d\", \"vr_common_unittests\": \"29ec1f3d221d093f52a0a53ce490cdd1858af59a\", \"vr_pixeltests\": \"80568fada6440fbdeb7b142173cdfbe86d7d384b\", \"webdriver_wpt_tests\": \"7da16327dbb3dd7527bfebb2afd51d91dc8315ab\", \"weblayer_browsertests\": \"ebcd9581720c085a403a5cb9fff54ffbf87e82cc\", \"weblayer_unittests\": \"9dcf59db5c8251f89e2da8043fd36e3cbacce3b5\", \"wm_unittests\": \"2d3d675f2067824fde740847e210e1389774b14c\", \"wtf_unittests\": \"8efd1c0b72c10192634110a8bbc0553df8072162\", \"x11_unittests\": \"03126b404231236bc3bd485ddc4c19781b57ca78\", \"xr_browser_tests\": \"eae20b6cb982322f069eb5adad7bfa43b9bf63e6\", \"zlib_unittests\": \"4ea0948e974c2749797518cf2b07f4c6088f3eaa\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0111b226b6a2e1a66cbbf5d93fde4367495bc99d98428795ba3e931083c57f27_v2\", \"builder_4107b14b7476412be0315d38f8e370eb2ac2c1b388bb8ffb59d7bdd8f08032e9_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_a50faa98e00aefb88600aa330f70664f9b4b54ee0ff51cb8b03a7f0a0b737b38_v2\", \"builder_a7b9f8880f1f76f2cce14c79e9b35da9a713af6654c6d1393cfbcc356d1ede5d_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-199-zhcu\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607582518516841",
"status": "COMPLETED",
"status_changed_ts": "1607585359103525",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/178520",
"builder:Linux Builder (dbg)",
"buildset:commit/git/97f817cc2f671bd1efb05e919b1220798b08332f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97f817cc2f671bd1efb05e919b1220798b08332f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026751648650000704",
"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/8861321586424145232/+/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:50642f8b24243810",
"user_agent:luci-scheduler"
],
"updated_ts": "1607585359108548",
"url": "https://ci.chromium.org/b/8861321586424145232",
"utcnow_ts": "1607585359551756"
}
}
[07:29:19.576] Invocation finished in 52m25.263577808s with status SUCCEEDED