buildbucket: <
  server: "cr-buildbucket.appspot.com"
  bucket: "luci.celab.ci"
  builder: "Windows"
>

• 4edea2429c1f152f6a78a07907d41038ff15ea92 (4 years ago)

{
  "branch": "refs/heads/master",
  "repository": "https://chromium.googlesource.com/enterprise/cel.git",
  "revision": "4edea2429c1f152f6a78a07907d41038ff15ea92"
}

• buildset:commit/git/4edea2429c1f152f6a78a07907d41038ff15ea92
• buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4edea2429c1f152f6a78a07907d41038ff15ea92
• gitiles_ref:refs/heads/master

[21:58:42.210] New invocation is queued and will start shortly
[21:58:43.431] Starting the invocation (attempt 1)
[21:58:43.492] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:58:43.493] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:58:43.493] Buildbucket request:
{
  "bucket": "luci.celab.ci",
  "client_operation_id": "9053419759229262304",
  "parameters_json": "{\"builder_name\":\"Windows\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"4edea2429c1f152f6a78a07907d41038ff15ea92\"},\"id\":\"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@4edea2429c1f152f6a78a07907d41038ff15ea92\",\"title\":\"4edea2429c1f152f6a78a07907d41038ff15ea92\",\"url\":\"https://chromium.googlesource.com/enterprise/cel.git/+/4edea2429c1f152f6a78a07907d41038ff15ea92\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/enterprise/cel.git\",\"revision\":\"4edea2429c1f152f6a78a07907d41038ff15ea92\"}}",
  "pubsub_callback": {
    "auth_token": "...",
    "topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  },
  "tags": [
    "builder:Windows",
    "scheduler_invocation_id:9053419759229262304",
    "scheduler_job_id:celab/Windows",
    "user_agent:luci-scheduler",
    "buildset:commit/git/4edea2429c1f152f6a78a07907d41038ff15ea92",
    "buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4edea2429c1f152f6a78a07907d41038ff15ea92",
    "gitiles_ref:refs/heads/master"
  ]
}
[21:58:44.813] Buildbucket response:
{
  "build": {
    "bucket": "luci.celab.ci",
    "canary_preference": "PROD",
    "created_by": "project:celab",
    "created_ts": "1582149523740066",
    "id": "8887989696953328592",
    "parameters_json": "{\"builder_name\": \"Windows\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\"}, \"id\": \"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@4edea2429c1f152f6a78a07907d41038ff15ea92\", \"title\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\", \"url\": \"https://chromium.googlesource.com/enterprise/cel.git/+/4edea2429c1f152f6a78a07907d41038ff15ea92\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\"}}",
    "project": "celab",
    "result_details_json": "{\"properties\": {}}",
    "service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "status": "SCHEDULED",
    "status_changed_ts": "1582149524482732",
    "tags": [
      "builder:Windows",
      "buildset:commit/git/4edea2429c1f152f6a78a07907d41038ff15ea92",
      "buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4edea2429c1f152f6a78a07907d41038ff15ea92",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9053419759229262304",
      "scheduler_job_id:celab/Windows",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8887989696953328592/+/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": "1582149524482852",
    "url": "https://ci.chromium.org/b/8887989696953328592",
    "utcnow_ts": "1582149524801998"
  }
}
[21:58:44.813] Task URL: https://ci.chromium.org/b/8887989696953328592
[21:58:44.813] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:2:0) after 1m0s
[21:58:46.398] Received PubSub notification, asking Buildbucket for the build status
[21:58:46.605] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:44.801] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:2:0)
[21:59:44.801] Timer tick, asking Buildbucket for the build status
[21:59:44.888] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:44.888] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:4:0) after 1m0s
[22:00:45.042] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:4:0)
[22:00:45.042] Timer tick, asking Buildbucket for the build status
[22:00:45.186] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:45.186] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:5:0) after 1m0s
[22:01:45.166] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:5:0)
[22:01:45.167] Timer tick, asking Buildbucket for the build status
[22:01:45.359] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:45.359] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:6:0) after 1m0s
[22:02:45.498] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:6:0)
[22:02:45.498] Timer tick, asking Buildbucket for the build status
[22:02:45.880] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:45.880] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:7:0) after 1m0s
[22:03:45.943] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:7:0)
[22:03:45.943] Timer tick, asking Buildbucket for the build status
[22:03:46.271] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:46.271] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:8:0) after 1m0s
[22:04:46.422] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:8:0)
[22:04:46.422] Timer tick, asking Buildbucket for the build status
[22:04:46.802] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:46.803] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:9:0) after 1m0s
[22:05:46.911] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:9:0)
[22:05:46.911] Timer tick, asking Buildbucket for the build status
[22:05:47.033] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:47.033] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:10:0) after 1m0s
[22:06:47.015] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:10:0)
[22:06:47.015] Timer tick, asking Buildbucket for the build status
[22:06:48.310] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:48.310] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:11:0) after 1m0s
[22:07:48.366] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:11:0)
[22:07:48.366] Timer tick, asking Buildbucket for the build status
[22:07:48.479] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:48.479] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:12:0) after 1m0s
[22:08:48.564] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:12:0)
[22:08:48.564] Timer tick, asking Buildbucket for the build status
[22:08:48.707] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:48.707] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:13:0) after 1m0s
[22:09:48.739] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:13:0)
[22:09:48.739] Timer tick, asking Buildbucket for the build status
[22:09:49.012] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:49.012] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:14:0) after 1m0s
[22:10:49.097] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:14:0)
[22:10:49.097] Timer tick, asking Buildbucket for the build status
[22:10:49.495] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:49.495] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:15:0) after 1m0s
[22:11:49.605] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:15:0)
[22:11:49.605] Timer tick, asking Buildbucket for the build status
[22:11:49.720] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:49.720] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:16:0) after 1m0s
[22:12:49.753] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:16:0)
[22:12:49.753] Timer tick, asking Buildbucket for the build status
[22:12:49.852] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:49.852] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:17:0) after 1m0s
[22:13:49.897] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:17:0)
[22:13:49.897] Timer tick, asking Buildbucket for the build status
[22:13:50.175] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:50.175] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:18:0) after 1m0s
[22:14:50.194] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:18:0)
[22:14:50.194] Timer tick, asking Buildbucket for the build status
[22:14:50.322] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:50.322] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:19:0) after 1m0s
[22:15:50.338] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:19:0)
[22:15:50.338] Timer tick, asking Buildbucket for the build status
[22:15:50.482] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:50.482] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:20:0) after 1m0s
[22:16:50.485] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:20:0)
[22:16:50.485] Timer tick, asking Buildbucket for the build status
[22:16:50.755] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:50.755] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:21:0) after 1m0s
[22:17:50.791] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:21:0)
[22:17:50.791] Timer tick, asking Buildbucket for the build status
[22:17:50.998] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:50.998] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:22:0) after 1m0s
[22:18:50.995] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:22:0)
[22:18:50.995] Timer tick, asking Buildbucket for the build status
[22:18:51.095] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:51.095] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:23:0) after 1m0s
[22:19:51.352] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:23:0)
[22:19:51.352] Timer tick, asking Buildbucket for the build status
[22:19:51.484] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:51.484] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:24:0) after 1m0s
[22:20:51.517] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:24:0)
[22:20:51.517] Timer tick, asking Buildbucket for the build status
[22:20:51.659] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:51.659] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:25:0) after 1m0s
[22:21:51.755] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:25:0)
[22:21:51.755] Timer tick, asking Buildbucket for the build status
[22:21:51.856] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:51.856] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:26:0) after 1m0s
[22:22:51.883] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:26:0)
[22:22:51.883] Timer tick, asking Buildbucket for the build status
[22:22:52.111] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:52.111] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:27:0) after 1m0s
[22:23:52.164] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:27:0)
[22:23:52.164] Timer tick, asking Buildbucket for the build status
[22:23:52.603] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:52.603] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:28:0) after 1m0s
[22:24:52.630] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:28:0)
[22:24:52.630] Timer tick, asking Buildbucket for the build status
[22:24:52.862] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:52.862] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:29:0) after 1m0s
[22:25:52.883] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:29:0)
[22:25:52.883] Timer tick, asking Buildbucket for the build status
[22:25:52.970] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:52.970] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:30:0) after 1m0s
[22:26:53.113] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:30:0)
[22:26:53.113] Timer tick, asking Buildbucket for the build status
[22:26:53.284] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:53.284] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:31:0) after 1m0s
[22:27:53.404] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:31:0)
[22:27:53.404] Timer tick, asking Buildbucket for the build status
[22:27:53.569] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:53.569] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:32:0) after 1m0s
[22:28:54.107] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:32:0)
[22:28:54.107] Timer tick, asking Buildbucket for the build status
[22:28:54.219] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:54.219] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:33:0) after 1m0s
[22:29:54.265] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:33:0)
[22:29:54.265] Timer tick, asking Buildbucket for the build status
[22:29:54.391] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:54.391] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:34:0) after 1m0s
[22:30:54.462] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:34:0)
[22:30:54.462] Timer tick, asking Buildbucket for the build status
[22:30:54.619] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:54.619] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:35:0) after 1m0s
[22:31:54.645] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:35:0)
[22:31:54.645] Timer tick, asking Buildbucket for the build status
[22:31:54.753] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:54.753] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:36:0) after 1m0s
[22:32:54.790] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:36:0)
[22:32:54.790] Timer tick, asking Buildbucket for the build status
[22:32:54.949] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:54.949] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:37:0) after 1m0s
[22:33:54.995] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:37:0)
[22:33:54.995] Timer tick, asking Buildbucket for the build status
[22:33:55.100] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:55.100] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:38:0) after 1m0s
[22:34:55.127] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:38:0)
[22:34:55.127] Timer tick, asking Buildbucket for the build status
[22:34:55.352] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:55.352] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:39:0) after 1m0s
[22:35:55.389] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:39:0)
[22:35:55.389] Timer tick, asking Buildbucket for the build status
[22:35:55.486] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:55.486] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:40:0) after 1m0s
[22:36:55.508] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:40:0)
[22:36:55.508] Timer tick, asking Buildbucket for the build status
[22:36:55.801] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:55.801] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:41:0) after 1m0s
[22:37:55.948] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:41:0)
[22:37:55.948] Timer tick, asking Buildbucket for the build status
[22:37:56.124] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:56.124] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:42:0) after 1m0s
[22:38:56.199] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:42:0)
[22:38:56.199] Timer tick, asking Buildbucket for the build status
[22:38:56.468] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:56.468] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:43:0) after 1m0s
[22:39:56.600] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:43:0)
[22:39:56.600] Timer tick, asking Buildbucket for the build status
[22:39:56.710] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:56.710] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:44:0) after 1m0s
[22:40:56.881] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:44:0)
[22:40:56.881] Timer tick, asking Buildbucket for the build status
[22:40:57.088] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:57.088] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:45:0) after 1m0s
[22:41:57.122] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:45:0)
[22:41:57.122] Timer tick, asking Buildbucket for the build status
[22:41:57.300] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:57.300] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:46:0) after 1m0s
[22:42:57.486] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:46:0)
[22:42:57.486] Timer tick, asking Buildbucket for the build status
[22:42:57.597] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:57.597] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:47:0) after 1m0s
[22:43:57.721] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:47:0)
[22:43:57.721] Timer tick, asking Buildbucket for the build status
[22:43:57.808] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:57.808] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:48:0) after 1m0s
[22:44:57.907] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:48:0)
[22:44:57.907] Timer tick, asking Buildbucket for the build status
[22:44:58.020] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:58.020] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:49:0) after 1m0s
[22:45:58.092] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:49:0)
[22:45:58.092] Timer tick, asking Buildbucket for the build status
[22:45:58.193] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:58.193] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:50:0) after 1m0s
[22:46:58.377] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:50:0)
[22:46:58.377] Timer tick, asking Buildbucket for the build status
[22:46:58.666] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:58.666] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:51:0) after 1m0s
[22:47:58.938] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:51:0)
[22:47:58.938] Timer tick, asking Buildbucket for the build status
[22:47:59.284] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:59.284] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:52:0) after 1m0s
[22:48:59.290] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:52:0)
[22:48:59.290] Timer tick, asking Buildbucket for the build status
[22:48:59.682] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:59.682] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:53:0) after 1m0s
[22:49:59.834] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:53:0)
[22:49:59.834] Timer tick, asking Buildbucket for the build status
[22:49:59.933] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:59.933] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:54:0) after 1m0s
[22:51:00.106] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:54:0)
[22:51:00.106] Timer tick, asking Buildbucket for the build status
[22:51:00.275] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:00.275] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:55:0) after 1m0s
[22:52:00.374] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:55:0)
[22:52:00.374] Timer tick, asking Buildbucket for the build status
[22:52:00.742] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:00.742] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:56:0) after 1m0s
[22:53:00.912] Handling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:56:0)
[22:53:00.912] Timer tick, asking Buildbucket for the build status
[22:53:01.356] Build 8887989696953328592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:01.356] Scheduling timer "check-buildbucket-build-status" (celab/Windows:9053419759229262304:57:0) after 1m0s
[22:53:51.794] Received PubSub notification, asking Buildbucket for the build status
[22:53:52.206] Build 8887989696953328592: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:53:52.206] Buildbucket build:
{
  "build": {
    "bucket": "luci.celab.ci",
    "canary_preference": "PROD",
    "completed_ts": "1582152830938015",
    "created_by": "project:celab",
    "created_ts": "1582149523740066",
    "id": "8887989696953328592",
    "parameters_json": "{\"builder_name\": \"Windows\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\"}, \"id\": \"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@4edea2429c1f152f6a78a07907d41038ff15ea92\", \"title\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\", \"url\": \"https://chromium.googlesource.com/enterprise/cel.git/+/4edea2429c1f152f6a78a07907d41038ff15ea92\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\"}}",
    "project": "celab",
    "result": "SUCCESS",
    "result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\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\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\"}, \"id\": \"https://chromium.googlesource.com/enterprise/cel.git/+/refs/heads/master@4edea2429c1f152f6a78a07907d41038ff15ea92\", \"title\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\", \"url\": \"https://chromium.googlesource.com/enterprise/cel.git/+/4edea2429c1f152f6a78a07907d41038ff15ea92\"}]}, \"bot_id\": \"luci-flex-ci-win10-9-gw7o\", \"branch\": \"refs/heads/master\", \"buildername\": \"Windows\", \"got_revision\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\", \"include\": \"core\", \"path_config\": \"generic\", \"pool_name\": \"celab-ci\", \"pool_size\": 5.0, \"recipe\": \"celab\", \"repository\": \"https://chromium.googlesource.com/enterprise/cel.git\", \"revision\": \"4edea2429c1f152f6a78a07907d41038ff15ea92\", \"target_cpu\": \"x64\", \"tests\": \"*\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_07b540a2be04efe845abb5b3fbb2a19f60f3a424f4b2e199673937b6c5bd36bd_v2\", \"builder_b0b04099bb690e9c384bef7c731ba3d84bc3c2b6c820c2fd83d99c0d5609e755_v2\", \"git\", \"goma_v2\", \"infra_gclient_with_go\", \"vpython\", \"win_toolchain\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-flex-ci-win10-9-gw7o\"], \"image\": [\"chrome-win10-1703-19112100-3085293b610\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
    "service_account": "celab-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
    "started_ts": "1582149525460859",
    "status": "COMPLETED",
    "status_changed_ts": "1582152831400245",
    "tags": [
      "builder:Windows",
      "buildset:commit/git/4edea2429c1f152f6a78a07907d41038ff15ea92",
      "buildset:commit/gitiles/chromium.googlesource.com/enterprise/cel/+/4edea2429c1f152f6a78a07907d41038ff15ea92",
      "gitiles_ref:refs/heads/master",
      "scheduler_invocation_id:9053419759229262304",
      "scheduler_job_id:celab/Windows",
      "swarming_hostname:chromium-swarm.appspot.com",
      "swarming_tag:log_location:logdog://logs.chromium.org/celab/buildbucket/cr-buildbucket.appspot.com/8887989696953328592/+/annotations",
      "swarming_tag:luci_project:celab",
      "swarming_tag:os:Windows",
      "swarming_tag:recipe_name:celab",
      "swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
      "swarming_task_id:4a784766c4002f10",
      "user_agent:luci-scheduler"
    ],
    "updated_ts": "1582152831444752",
    "url": "https://ci.chromium.org/b/8887989696953328592",
    "utcnow_ts": "1582152832185886"
  }
}
[22:53:52.206] Invocation finished in 55m10.019984427s with status SUCCEEDED