[05:48:38.279] New invocation is queued and will start shortly
[05:48:39.378] Starting the invocation (attempt 1)
[05:48:39.420] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:48:39.420] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:48:39.420] Buildbucket request:
{
"bucket": "luci.celab.ci",
"client_operation_id": "9028294833808735152",
"parameters_json": "{\"builder_name\":\"Linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"72fda0e718736b74ed5a562a58b20d9d30440155\"},\"id\":\"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@72fda0e718736b74ed5a562a58b20d9d30440155\",\"title\":\"72fda0e718736b74ed5a562a58b20d9d30440155\",\"url\":\"https://chromium.googlesource.com/enterprise/cel.git/+/72fda0e718736b74ed5a562a58b20d9d30440155\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"72fda0e718736b74ed5a562a58b20d9d30440155\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux",
"scheduler_invocation_id:9028294833808735152",
"scheduler_job_id:celab/Linux",
"user_agent:luci-scheduler",
"buildset:commit/git/72fda0e718736b74ed5a562a58b20d9d30440155",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/72fda0e718736b74ed5a562a58b20d9d30440155",
"gitiles_ref:refs/heads/master"
]
}
[05:48:39.921] Buildbucket response:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "PROD",
"created_by": "project:celab",
"created_ts": "1606110519521830",
"id": "8862864771840980288",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\"}, \"id\": \"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@72fda0e718736b74ed5a562a58b20d9d30440155\", \"title\": \"72fda0e718736b74ed5a562a58b20d9d30440155\", \"url\": \"https://chromium.googlesource.com/enterprise/cel.git/+/72fda0e718736b74ed5a562a58b20d9d30440155\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\"}}",
"project": "celab",
"result_details_json": "{\"properties\": {}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606110519795198",
"tags": [
"builder:Linux",
"buildset:commit/git/72fda0e718736b74ed5a562a58b20d9d30440155",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/72fda0e718736b74ed5a562a58b20d9d30440155",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028294833808735152",
"scheduler_job_id:celab/Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8862864771840980288/+/annotations",
"swarming_tag:luci_project:celab",
"swarming_tag:recipe_name:celab",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1606110519795323",
"url": "https://ci.chromium.org/b/8862864771840980288",
"utcnow_ts": "1606110519914805"
}
}
[05:48:39.921] Task URL: https://ci.chromium.org/b/8862864771840980288
[05:48:39.921] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:2:0) after 1m0s
[05:49:24.321] Received PubSub notification, asking Buildbucket for the build status
[05:49:24.484] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:39.964] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:2:0)
[05:49:39.964] Timer tick, asking Buildbucket for the build status
[05:49:40.350] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:40.350] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:4:0) after 1m0s
[05:50:40.377] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:4:0)
[05:50:40.377] Timer tick, asking Buildbucket for the build status
[05:50:40.745] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:40.745] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:5:0) after 1m0s
[05:51:40.820] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:5:0)
[05:51:40.820] Timer tick, asking Buildbucket for the build status
[05:51:40.879] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:40.879] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:6:0) after 1m0s
[05:52:40.947] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:6:0)
[05:52:40.947] Timer tick, asking Buildbucket for the build status
[05:52:41.051] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:41.051] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:7:0) after 1m0s
[05:53:41.074] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:7:0)
[05:53:41.074] Timer tick, asking Buildbucket for the build status
[05:53:41.152] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:41.152] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:8:0) after 1m0s
[05:54:41.180] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:8:0)
[05:54:41.180] Timer tick, asking Buildbucket for the build status
[05:54:41.546] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:41.546] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:9:0) after 1m0s
[05:55:41.564] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:9:0)
[05:55:41.564] Timer tick, asking Buildbucket for the build status
[05:55:41.630] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:41.630] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:10:0) after 1m0s
[05:56:41.685] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:10:0)
[05:56:41.685] Timer tick, asking Buildbucket for the build status
[05:56:41.763] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:41.763] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:11:0) after 1m0s
[05:57:41.800] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:11:0)
[05:57:41.800] Timer tick, asking Buildbucket for the build status
[05:57:41.877] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:41.877] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:12:0) after 1m0s
[05:58:41.934] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:12:0)
[05:58:41.934] Timer tick, asking Buildbucket for the build status
[05:58:42.022] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:42.022] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:13:0) after 1m0s
[05:59:42.111] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:13:0)
[05:59:42.111] Timer tick, asking Buildbucket for the build status
[05:59:42.195] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:42.196] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:14:0) after 1m0s
[06:00:42.188] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:14:0)
[06:00:42.188] Timer tick, asking Buildbucket for the build status
[06:00:42.502] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:42.502] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:15:0) after 1m0s
[06:01:49.307] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:15:0)
[06:01:49.307] Timer tick, asking Buildbucket for the build status
[06:01:49.439] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:49.439] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:16:0) after 1m0s
[06:02:49.615] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:16:0)
[06:02:49.615] Timer tick, asking Buildbucket for the build status
[06:02:49.687] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:49.687] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:17:0) after 1m0s
[06:03:49.979] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:17:0)
[06:03:49.979] Timer tick, asking Buildbucket for the build status
[06:03:50.465] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:50.466] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:18:0) after 1m0s
[06:04:50.493] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:18:0)
[06:04:50.493] Timer tick, asking Buildbucket for the build status
[06:04:50.581] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:50.581] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:19:0) after 1m0s
[06:05:50.720] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:19:0)
[06:05:50.720] Timer tick, asking Buildbucket for the build status
[06:05:50.782] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:50.782] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:20:0) after 1m0s
[06:06:50.922] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:20:0)
[06:06:50.922] Timer tick, asking Buildbucket for the build status
[06:06:51.060] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:51.060] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:21:0) after 1m0s
[06:07:51.187] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:21:0)
[06:07:51.187] Timer tick, asking Buildbucket for the build status
[06:07:51.283] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:51.283] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:22:0) after 1m0s
[06:08:51.568] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:22:0)
[06:08:51.568] Timer tick, asking Buildbucket for the build status
[06:08:51.665] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:51.665] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:23:0) after 1m0s
[06:09:51.740] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:23:0)
[06:09:51.740] Timer tick, asking Buildbucket for the build status
[06:09:51.887] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:51.887] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:24:0) after 1m0s
[06:10:51.984] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:24:0)
[06:10:51.984] Timer tick, asking Buildbucket for the build status
[06:10:52.062] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:52.062] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:25:0) after 1m0s
[06:11:52.136] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:25:0)
[06:11:52.136] Timer tick, asking Buildbucket for the build status
[06:11:52.205] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:52.205] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:26:0) after 1m0s
[06:12:52.299] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:26:0)
[06:12:52.299] Timer tick, asking Buildbucket for the build status
[06:12:52.367] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:52.367] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:27:0) after 1m0s
[06:13:52.373] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:27:0)
[06:13:52.373] Timer tick, asking Buildbucket for the build status
[06:13:52.507] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:52.507] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:28:0) after 1m0s
[06:14:52.580] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:28:0)
[06:14:52.580] Timer tick, asking Buildbucket for the build status
[06:14:52.650] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:52.650] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:29:0) after 1m0s
[06:15:52.669] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:29:0)
[06:15:52.669] Timer tick, asking Buildbucket for the build status
[06:15:52.854] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:52.854] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:30:0) after 1m0s
[06:16:53.981] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:30:0)
[06:16:53.981] Timer tick, asking Buildbucket for the build status
[06:16:54.053] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:54.053] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:31:0) after 1m0s
[06:17:54.673] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:31:0)
[06:17:54.673] Timer tick, asking Buildbucket for the build status
[06:17:54.749] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:54.749] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:32:0) after 1m0s
[06:18:55.427] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:32:0)
[06:18:55.427] Timer tick, asking Buildbucket for the build status
[06:18:55.630] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:55.630] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:33:0) after 1m0s
[06:19:55.704] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:33:0)
[06:19:55.704] Timer tick, asking Buildbucket for the build status
[06:19:55.787] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:55.787] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:34:0) after 1m0s
[06:20:55.976] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:34:0)
[06:20:55.976] Timer tick, asking Buildbucket for the build status
[06:20:56.055] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:56.055] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:35:0) after 1m0s
[06:21:56.172] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:35:0)
[06:21:56.172] Timer tick, asking Buildbucket for the build status
[06:21:56.384] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:56.384] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:36:0) after 1m0s
[06:22:56.513] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:36:0)
[06:22:56.513] Timer tick, asking Buildbucket for the build status
[06:22:56.602] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:56.602] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:37:0) after 1m0s
[06:23:56.659] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:37:0)
[06:23:56.659] Timer tick, asking Buildbucket for the build status
[06:23:56.839] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:56.839] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:38:0) after 1m0s
[06:24:56.893] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:38:0)
[06:24:56.893] Timer tick, asking Buildbucket for the build status
[06:24:56.979] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:56.979] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:39:0) after 1m0s
[06:25:57.134] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:39:0)
[06:25:57.134] Timer tick, asking Buildbucket for the build status
[06:25:57.203] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:57.203] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:40:0) after 1m0s
[06:26:57.356] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:40:0)
[06:26:57.356] Timer tick, asking Buildbucket for the build status
[06:26:57.434] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:57.434] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:41:0) after 1m0s
[06:27:57.606] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:41:0)
[06:27:57.606] Timer tick, asking Buildbucket for the build status
[06:27:58.006] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:58.006] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:42:0) after 1m0s
[06:28:58.059] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:42:0)
[06:28:58.059] Timer tick, asking Buildbucket for the build status
[06:28:58.230] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:58.230] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:43:0) after 1m0s
[06:29:58.327] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:43:0)
[06:29:58.327] Timer tick, asking Buildbucket for the build status
[06:29:58.401] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:58.401] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:44:0) after 1m0s
[06:30:58.571] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:44:0)
[06:30:58.571] Timer tick, asking Buildbucket for the build status
[06:30:58.970] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:58.970] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:45:0) after 1m0s
[06:31:59.128] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:45:0)
[06:31:59.128] Timer tick, asking Buildbucket for the build status
[06:31:59.199] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:59.199] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:46:0) after 1m0s
[06:32:59.321] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:46:0)
[06:32:59.321] Timer tick, asking Buildbucket for the build status
[06:32:59.414] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:59.414] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:47:0) after 1m0s
[06:33:59.632] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:47:0)
[06:33:59.632] Timer tick, asking Buildbucket for the build status
[06:33:59.739] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:59.739] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:48:0) after 1m0s
[06:34:59.842] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:48:0)
[06:34:59.842] Timer tick, asking Buildbucket for the build status
[06:34:59.924] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:59.924] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:49:0) after 1m0s
[06:35:59.997] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:49:0)
[06:35:59.997] Timer tick, asking Buildbucket for the build status
[06:36:00.195] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:00.195] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:50:0) after 1m0s
[06:37:00.207] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:50:0)
[06:37:00.207] Timer tick, asking Buildbucket for the build status
[06:37:00.282] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:00.282] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:51:0) after 1m0s
[06:38:00.376] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:51:0)
[06:38:00.376] Timer tick, asking Buildbucket for the build status
[06:38:00.504] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:00.504] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:52:0) after 1m0s
[06:39:01.555] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:52:0)
[06:39:01.555] Timer tick, asking Buildbucket for the build status
[06:39:01.908] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:01.908] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:53:0) after 1m0s
[06:40:01.946] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:53:0)
[06:40:01.946] Timer tick, asking Buildbucket for the build status
[06:40:02.031] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:02.031] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:54:0) after 1m0s
[06:41:02.163] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:54:0)
[06:41:02.163] Timer tick, asking Buildbucket for the build status
[06:41:02.331] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:02.331] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:55:0) after 1m0s
[06:42:02.411] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:55:0)
[06:42:02.411] Timer tick, asking Buildbucket for the build status
[06:42:02.478] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:02.478] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:56:0) after 1m0s
[06:43:02.699] Handling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:56:0)
[06:43:02.699] Timer tick, asking Buildbucket for the build status
[06:43:02.850] Build 8862864771840980288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:02.850] Scheduling timer "check-buildbucket-build-status" (celab/Linux:9028294833808735152:57:0) after 1m0s
[06:43:35.222] Received PubSub notification, asking Buildbucket for the build status
[06:43:35.281] Build 8862864771840980288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:43:35.281] Buildbucket build:
{
"build": {
"bucket": "luci.celab.ci",
"canary_preference": "PROD",
"completed_ts": "1606113814540944",
"created_by": "project:celab",
"created_ts": "1606110519521830",
"id": "8862864771840980288",
"parameters_json": "{\"builder_name\": \"Linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\"}, \"id\": \"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@72fda0e718736b74ed5a562a58b20d9d30440155\", \"title\": \"72fda0e718736b74ed5a562a58b20d9d30440155\", \"url\": \"https://chromium.googlesource.com/enterprise/cel.git/+/72fda0e718736b74ed5a562a58b20d9d30440155\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\"}}",
"project": "celab",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/mnt/data/b/s/w/ir/cache\", \"temp_dir\": \"/mnt/data/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\"}, \"id\": \"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@72fda0e718736b74ed5a562a58b20d9d30440155\", \"title\": \"72fda0e718736b74ed5a562a58b20d9d30440155\", \"url\": \"https://chromium.googlesource.com/enterprise/cel.git/+/72fda0e718736b74ed5a562a58b20d9d30440155\"}]}, \"bot_id\": \"vm78-m1\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux\", \"got_revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\", \"path_config\": \"generic\", \"pool_name\": \"celab-ci\", \"pool_size\": 5.0, \"recipe\": \"celab\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"72fda0e718736b74ed5a562a58b20d9d30440155\", \"target_cpu\": \"x64\", \"tests\": \"*\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_08d95548a5b2763894be45da75746e0a26ecb57e0e5972c9d07f9ce07d542665_v2\", \"builder_0c2213e2471b4804ce7cc02c147d9769f9d07f20fd3fd447c453f4900380938c_v2\", \"builder_104bd9a76c554f27456c85a5491ddf5f85dcadb4b39968a4576655287a5cf526_v2\", \"builder_10cad3ca224a3c3cd7b7ab0b8c6a71270b500a4c9540c3fd71bf5a4ed7745605_v2\", \"builder_122af0527ca901913040e5b6f69f069c64dbe5b8a2d1f30e44843753d0aef6ca_v2\", \"builder_149fed94fc0315f63a3ac899b53d3cfedce2ab533d8d891f9abe32610aa3106a_v2\", \"builder_15cfdcbddd47f31933ea7bcf8631d84c342cdb6dc95dfa5a159b8a19b9d950f3_v2\", \"builder_1d469ce73f696de6efd5e95df00b1ffe01dc80ee8f70f8b5f0d804841ba527c2_v2\", \"builder_2aadde7407bb933c333159fc1542f89ab9fb29eba1b9aeaf095fdcde6a12dee8_v2\", \"builder_2cce8a54f10ae06c8062a815e7239fc5a429d139ce239608ce647c8c02b455c4_v2\", \"builder_30037600afb0fc8938a6d537e3c45a46491e7159df17e0dae8492f75116b87ca_v2\", \"builder_3f18df2cc6470c5e4a9cbfb5d10fffd04c3fada4f0e81b3cbed04a30d82e4b94_v2\", \"builder_41bff59e48143683eee7e209c35176da7d4c8f0bf6fc4cc531114b3142934c50_v2\", \"builder_4235cbca53d69e2265b4766f5a8bce926c6fc26ba44440d27213d5484c3aef8d_v2\", \"builder_449c2510a534bee5c7295f1727bde9860af936880631c6c900ad7524c472f417_v2\", \"builder_4c879b492ad4ee1f5d8245dfd05718fa204c14b1ae0accb81fd1e5b1e33f4700_v2\", \"builder_4f61cf2cbfd9eb5c25a7a3b6ea41124cd00feac67e3b26b6e8329f7acb770939_v2\", \"builder_5bf551a0d5d177b55ce526f99d0a6f2ea5c5827f96722dd0bf2e7c331b5e9e59_v2\", \"builder_61fc32c3bc5a7ee2a26f46d61db55ba1f83c918285cc8d2dc6086b96201d8c90_v2\", \"builder_71ef69d0114959f59330b4253e21b16bb98a7f5d4c573a6f3e7120b7b72ed97a_v2\", \"builder_72878cc5bd2da050799d24d67c7f6569382e23dde41c6ed110c079f65f02b0f1_v2\", \"builder_78893cbd4c292ba203eb7a045a56252df7f14bd0ad3b7b91fac8bfee09f12c2b_v2\", \"builder_7ea55abfea25d314356d803a5f0ad62170c3dfd6004ffb0ffd94b75873d1ce3b_v2\", \"builder_868e37e72a67c9e0062fe47d1f774c83ff68e6ed82c10475786eedb84b9e23f8_v2\", \"builder_96b937d0444ca0237e7399236bdd6728df75ff624b2c87b99974683f32fc3980_v2\", \"builder_a0644330fbd5fcc319e71650781113ccfe591e85172c7efdd4f375c5b7e5d777_v2\", \"builder_a09264ab5ee361cfbd3c6cbd9a947de48f12b06fe92b2f02cd9f989a2ddee582_v2\", \"builder_a25b9d306d94020ae7296883188cf45973114e65e21ed270d2dac1a6d2a1a00b_v2\", \"builder_a9d817498024731fbad1bd510fd3442e5f2ec3c82cfa77f4201923ca9f83e720_v2\", \"builder_ade509247dd25059f1eaa141a5cb597841f876685ca9aeb8535a3c7aae6fce13_v2\", \"builder_c4d21eced6de83d49b751ba6a9a51b24a2637effdae03eb3d3659643edf5411d_v2\", \"builder_c4e0d2919c9449e57616d926859a612456f61676d96fb8625e0b44c6d892c145_v2\", \"builder_d5b257be8ad26d68a3bb31ab5151acb76b44f6bebfdc79a93e0c4a27dfc6b7b4_v2\", \"builder_d6e34fbf0b97f42009d0e08c4c2190eabfc3f965b1a56dffd678d628cf424e8a_v2\", \"builder_da993b9614f8cad1e90520ed7300b06f4fe643b58f2ef83efe78001f9b5264ca_v2\", \"builder_e532bcdd57ded30329b523a80650fc9919762d52882c48730e8df054fe74357c_v2\", \"builder_ec75d5eb8f7498c41b88020a7acbce0dfbc8ce77097e132694281c2971626133_v2\", \"builder_ef25b88491dc562a084b4a4d622d1da7a429c7f5b0b47ec76bb5145e46035a01_v2\", \"builder_f03fc972fd74f67bd2e15a94ea28a57ee7e7ea7c6fd21129bd85bac28e06088b_v2\", \"builder_f58e49d4978dd47022c74d62848afbffa29b3ee52aa79f0e76d160631af2bf15_v2\", \"builder_f685126061b3b63fc349ffa1b5e53cf0d3cd3c1ef180ecb8e5b4299d10c61666_v2\", \"builder_faaedc22e3ac6fec098652e7f0fd10d4f879f8f9ad55ea8ef979585009ee36da_v2\", \"builder_ffd36e29a428b818c867dca5a22339cd31e7cf39c69354dd8e9deb8785e0186c_v2\", \"git\", \"gocache\", \"goma_v2\", \"gopath\", \"infra_gclient_with_go\", \"vpython\", \"win_toolchain\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-avx2\"], \"gce\": [\"0\"], \"gpu\": [\"none\"], \"id\": [\"vm78-m1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"0\"], \"locale\": [\"en_US.ISO8859-1\"], \"machine_type\": [\"n1-highcpu-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.3\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5637-4d8b832\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m1\"]}}}",
"service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606110563307277",
"status": "COMPLETED",
"status_changed_ts": "1606113814980993",
"tags": [
"builder:Linux",
"buildset:commit/git/72fda0e718736b74ed5a562a58b20d9d30440155",
"buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/72fda0e718736b74ed5a562a58b20d9d30440155",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028294833808735152",
"scheduler_job_id:celab/Linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8862864771840980288/+/annotations",
"swarming_tag:luci_project:celab",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:celab",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:500c772cce5f5f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606113814993913",
"url": "https://ci.chromium.org/b/8862864771840980288",
"utcnow_ts": "1606113815272767"
}
}
[06:43:35.281] Invocation finished in 54m57.019895027s with status SUCCEEDED