[20:03:28.169] New invocation is queued and will start shortly
[20:03:29.327] Starting the invocation (attempt 1)
[20:03:29.378] Preparing PubSub topic for "https://cr-buildbucket-dev.appspot.com"
[20:03:29.378] PubSub topic is "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
[20:03:29.379] Buildbucket request:
{
"bucket": "luci.infra.ci",
"client_operation_id": "9040290448994511616",
"parameters_json": "{\"builder_name\":\"infra-continuous-win10-64\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler-dev.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"868837e887f1134781bff8745a6d81fe04a7ebc9\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@868837e887f1134781bff8745a6d81fe04a7ebc9\",\"title\":\"868837e887f1134781bff8745a6d81fe04a7ebc9\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/868837e887f1134781bff8745a6d81fe04a7ebc9\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"db8227dc00e2cb479f142d82ad9287cdf7e072cc\"},\"id\":\"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@db8227dc00e2cb479f142d82ad9287cdf7e072cc\",\"title\":\"db8227dc00e2cb479f142d82ad9287cdf7e072cc\",\"url\":\"https://chromium.googlesource.com/infra/infra/+/db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/infra/infra\",\"revision\":\"db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler-dev/topics/scheduler.buildbucket.cr-buildbucket-dev~appspot.gserviceaccount.com"
},
"tags": [
"builder:infra-continuous-win10-64",
"scheduler_invocation_id:9040290448994511616",
"scheduler_job_id:infra/infra-continuous-win10-64",
"user_agent:luci-scheduler-dev",
"buildset:commit/git/db8227dc00e2cb479f142d82ad9287cdf7e072cc",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/db8227dc00e2cb479f142d82ad9287cdf7e072cc",
"gitiles_ref:refs/heads/master"
]
}
[20:03:30.225] Buildbucket response:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1594670609518927",
"id": "8874860386912545296",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"868837e887f1134781bff8745a6d81fe04a7ebc9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@868837e887f1134781bff8745a6d81fe04a7ebc9\", \"title\": \"868837e887f1134781bff8745a6d81fe04a7ebc9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/868837e887f1134781bff8745a6d81fe04a7ebc9\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@db8227dc00e2cb479f142d82ad9287cdf7e072cc\", \"title\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594670610076633",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17263",
"builder:infra-continuous-win10-64",
"buildset:commit/git/db8227dc00e2cb479f142d82ad9287cdf7e072cc",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/db8227dc00e2cb479f142d82ad9287cdf7e072cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040290448994511616",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8874860386912545296/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1594670610076750",
"url": "https://luci-milo-dev.appspot.com/b/8874860386912545296",
"utcnow_ts": "1594670610216054"
}
}
[20:03:30.225] Task URL: https://luci-milo-dev.appspot.com/b/8874860386912545296
[20:03:30.225] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:2:0) after 1m0s
[20:04:30.247] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:2:0)
[20:04:30.247] Timer tick, asking Buildbucket for the build status
[20:04:30.391] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:04:30.391] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:3:0) after 1m0s
[20:05:30.423] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:3:0)
[20:05:30.423] Timer tick, asking Buildbucket for the build status
[20:05:30.549] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:05:30.549] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:4:0) after 1m0s
[20:06:30.623] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:4:0)
[20:06:30.623] Timer tick, asking Buildbucket for the build status
[20:06:30.866] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:06:30.866] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:5:0) after 1m0s
[20:07:30.893] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:5:0)
[20:07:30.893] Timer tick, asking Buildbucket for the build status
[20:07:31.035] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:07:31.035] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:6:0) after 1m0s
[20:08:31.105] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:6:0)
[20:08:31.105] Timer tick, asking Buildbucket for the build status
[20:08:31.234] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:08:31.234] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:7:0) after 1m0s
[20:09:31.259] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:7:0)
[20:09:31.259] Timer tick, asking Buildbucket for the build status
[20:09:31.499] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:09:31.499] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:8:0) after 1m0s
[20:10:31.523] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:8:0)
[20:10:31.523] Timer tick, asking Buildbucket for the build status
[20:10:31.666] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:10:31.666] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:9:0) after 1m0s
[20:11:31.693] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:9:0)
[20:11:31.693] Timer tick, asking Buildbucket for the build status
[20:11:31.803] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:11:31.803] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:10:0) after 1m0s
[20:12:31.830] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:10:0)
[20:12:31.830] Timer tick, asking Buildbucket for the build status
[20:12:31.945] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:12:31.945] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:11:0) after 1m0s
[20:13:32.014] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:11:0)
[20:13:32.014] Timer tick, asking Buildbucket for the build status
[20:13:32.144] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:13:32.144] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:12:0) after 1m0s
[20:14:32.170] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:12:0)
[20:14:32.170] Timer tick, asking Buildbucket for the build status
[20:14:32.287] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:14:32.287] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:13:0) after 1m0s
[20:15:32.363] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:13:0)
[20:15:32.363] Timer tick, asking Buildbucket for the build status
[20:15:32.480] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:15:32.480] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:14:0) after 1m0s
[20:16:32.935] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:14:0)
[20:16:32.935] Timer tick, asking Buildbucket for the build status
[20:16:33.059] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:16:33.059] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:15:0) after 1m0s
[20:17:33.192] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:15:0)
[20:17:33.192] Timer tick, asking Buildbucket for the build status
[20:17:33.328] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:17:33.328] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:16:0) after 1m0s
[20:18:33.418] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:16:0)
[20:18:33.418] Timer tick, asking Buildbucket for the build status
[20:18:33.573] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:18:33.573] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:17:0) after 1m0s
[20:19:33.596] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:17:0)
[20:19:33.596] Timer tick, asking Buildbucket for the build status
[20:19:33.816] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:19:33.816] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:18:0) after 1m0s
[20:20:33.912] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:18:0)
[20:20:33.912] Timer tick, asking Buildbucket for the build status
[20:20:34.047] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:34.047] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:19:0) after 1m0s
[20:21:34.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:19:0)
[20:21:34.127] Timer tick, asking Buildbucket for the build status
[20:21:34.290] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:34.290] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:20:0) after 1m0s
[20:22:34.316] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:20:0)
[20:22:34.316] Timer tick, asking Buildbucket for the build status
[20:22:34.439] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:34.439] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:21:0) after 1m0s
[20:23:34.461] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:21:0)
[20:23:34.461] Timer tick, asking Buildbucket for the build status
[20:23:34.593] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:34.593] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:22:0) after 1m0s
[20:24:34.622] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:22:0)
[20:24:34.623] Timer tick, asking Buildbucket for the build status
[20:24:34.847] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:34.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:23:0) after 1m0s
[20:25:34.872] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:23:0)
[20:25:34.872] Timer tick, asking Buildbucket for the build status
[20:25:35.082] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:35.082] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:24:0) after 1m0s
[20:26:35.116] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:24:0)
[20:26:35.116] Timer tick, asking Buildbucket for the build status
[20:26:35.299] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:35.299] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:25:0) after 1m0s
[20:27:35.425] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:25:0)
[20:27:35.425] Timer tick, asking Buildbucket for the build status
[20:27:35.544] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:35.544] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:26:0) after 1m0s
[20:28:35.576] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:26:0)
[20:28:35.576] Timer tick, asking Buildbucket for the build status
[20:28:35.756] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:35.756] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:27:0) after 1m0s
[20:29:35.816] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:27:0)
[20:29:35.816] Timer tick, asking Buildbucket for the build status
[20:29:35.981] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:35.981] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:28:0) after 1m0s
[20:30:36.013] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:28:0)
[20:30:36.013] Timer tick, asking Buildbucket for the build status
[20:30:36.212] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:36.212] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:29:0) after 1m0s
[20:31:36.331] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:29:0)
[20:31:36.331] Timer tick, asking Buildbucket for the build status
[20:31:36.446] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:36.446] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:30:0) after 1m0s
[20:32:36.472] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:30:0)
[20:32:36.472] Timer tick, asking Buildbucket for the build status
[20:32:36.637] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:36.637] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:31:0) after 1m0s
[20:33:36.659] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:31:0)
[20:33:36.659] Timer tick, asking Buildbucket for the build status
[20:33:36.831] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:36.831] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:32:0) after 1m0s
[20:34:36.859] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:32:0)
[20:34:36.859] Timer tick, asking Buildbucket for the build status
[20:34:36.978] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:36.978] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:33:0) after 1m0s
[20:35:37.012] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:33:0)
[20:35:37.012] Timer tick, asking Buildbucket for the build status
[20:35:37.172] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:37.172] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:34:0) after 1m0s
[20:36:37.216] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:34:0)
[20:36:37.216] Timer tick, asking Buildbucket for the build status
[20:36:37.358] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:37.358] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:35:0) after 1m0s
[20:37:37.411] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:35:0)
[20:37:37.411] Timer tick, asking Buildbucket for the build status
[20:37:37.582] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:37.582] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:36:0) after 1m0s
[20:38:37.612] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:36:0)
[20:38:37.612] Timer tick, asking Buildbucket for the build status
[20:38:37.742] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:37.742] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:37:0) after 1m0s
[20:39:37.860] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:37:0)
[20:39:37.860] Timer tick, asking Buildbucket for the build status
[20:39:38.038] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:38.038] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:38:0) after 1m0s
[20:40:38.113] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:38:0)
[20:40:38.113] Timer tick, asking Buildbucket for the build status
[20:40:38.444] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:38.444] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:39:0) after 1m0s
[20:41:38.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:39:0)
[20:41:38.515] Timer tick, asking Buildbucket for the build status
[20:41:38.649] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:38.649] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:40:0) after 1m0s
[20:42:39.314] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:40:0)
[20:42:39.314] Timer tick, asking Buildbucket for the build status
[20:42:39.437] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:42:39.437] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:41:0) after 1m0s
[20:43:39.463] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:41:0)
[20:43:39.463] Timer tick, asking Buildbucket for the build status
[20:43:39.585] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:43:39.585] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:42:0) after 1m0s
[20:44:39.618] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:42:0)
[20:44:39.618] Timer tick, asking Buildbucket for the build status
[20:44:39.747] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:44:39.747] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:43:0) after 1m0s
[20:45:40.702] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:43:0)
[20:45:40.702] Timer tick, asking Buildbucket for the build status
[20:45:40.863] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:45:40.863] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:44:0) after 1m0s
[20:46:40.899] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:44:0)
[20:46:40.899] Timer tick, asking Buildbucket for the build status
[20:46:41.027] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:46:41.027] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:45:0) after 1m0s
[20:47:41.060] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:45:0)
[20:47:41.060] Timer tick, asking Buildbucket for the build status
[20:47:41.183] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:47:41.183] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:46:0) after 1m0s
[20:48:41.315] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:46:0)
[20:48:41.315] Timer tick, asking Buildbucket for the build status
[20:48:41.443] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:48:41.443] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:47:0) after 1m0s
[20:49:41.515] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:47:0)
[20:49:41.515] Timer tick, asking Buildbucket for the build status
[20:49:41.662] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:49:41.662] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:48:0) after 1m0s
[20:50:41.716] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:48:0)
[20:50:41.716] Timer tick, asking Buildbucket for the build status
[20:50:41.866] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:50:41.867] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:49:0) after 1m0s
[20:51:41.934] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:49:0)
[20:51:41.934] Timer tick, asking Buildbucket for the build status
[20:51:42.090] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:51:42.090] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:50:0) after 1m0s
[20:52:42.127] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:50:0)
[20:52:42.127] Timer tick, asking Buildbucket for the build status
[20:52:42.253] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:52:42.254] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:51:0) after 1m0s
[20:53:42.280] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:51:0)
[20:53:42.280] Timer tick, asking Buildbucket for the build status
[20:53:42.425] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:53:42.425] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:52:0) after 1m0s
[20:54:42.451] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:52:0)
[20:54:42.451] Timer tick, asking Buildbucket for the build status
[20:54:42.594] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:54:42.594] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:53:0) after 1m0s
[20:55:42.678] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:53:0)
[20:55:42.678] Timer tick, asking Buildbucket for the build status
[20:55:42.847] Build 8874860386912545296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:55:42.847] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:54:0) after 1m0s
[20:55:48.139] Received PubSub notification, asking Buildbucket for the build status
[20:55:48.331] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:42.915] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:54:0)
[20:56:42.915] Timer tick, asking Buildbucket for the build status
[20:56:43.058] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:43.058] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:56:0) after 1m0s
[20:57:43.083] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:56:0)
[20:57:43.083] Timer tick, asking Buildbucket for the build status
[20:57:43.222] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:43.222] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:57:0) after 1m0s
[20:58:43.297] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:57:0)
[20:58:43.297] Timer tick, asking Buildbucket for the build status
[20:58:43.441] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:43.441] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:58:0) after 1m0s
[20:59:43.466] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:58:0)
[20:59:43.466] Timer tick, asking Buildbucket for the build status
[20:59:43.655] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:43.656] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:59:0) after 1m0s
[21:00:43.680] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:59:0)
[21:00:43.680] Timer tick, asking Buildbucket for the build status
[21:00:43.815] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:43.815] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:60:0) after 1m0s
[21:01:43.838] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:60:0)
[21:01:43.838] Timer tick, asking Buildbucket for the build status
[21:01:43.972] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:43.972] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:61:0) after 1m0s
[21:02:44.036] Handling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:61:0)
[21:02:44.036] Timer tick, asking Buildbucket for the build status
[21:02:44.192] Build 8874860386912545296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:44.192] Scheduling timer "check-buildbucket-build-status" (infra/infra-continuous-win10-64:9040290448994511616:62:0) after 1m0s
[21:03:37.855] Received PubSub notification, asking Buildbucket for the build status
[21:03:37.955] Build 8874860386912545296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:03:37.955] Buildbucket build:
{
"build": {
"bucket": "luci.infra.ci",
"canary_preference": "PROD",
"completed_ts": "1594674212437897",
"created_by": "project:infra",
"created_ts": "1594670609518927",
"id": "8874860386912545296",
"parameters_json": "{\"builder_name\": \"infra-continuous-win10-64\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler-dev.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"868837e887f1134781bff8745a6d81fe04a7ebc9\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@868837e887f1134781bff8745a6d81fe04a7ebc9\", \"title\": \"868837e887f1134781bff8745a6d81fe04a7ebc9\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/868837e887f1134781bff8745a6d81fe04a7ebc9\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}, \"id\": \"https://chromium.googlesource.com/infra/infra/+/refs/heads/master@db8227dc00e2cb479f142d82ad9287cdf7e072cc\", \"title\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\", \"url\": \"https://chromium.googlesource.com/infra/infra/+/db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/infra/infra\", \"revision\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"got_revision\": \"db8227dc00e2cb479f142d82ad9287cdf7e072cc\", \"got_revision_cp\": \"refs/heads/master@{#33236}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_82ed111fed7ea8169e482f00b5f3e5338a65daa41208585de67cb0a736b1d538_v2\", \"builder_a9c54e560aab64ffcab932ab26ad711a72ff73dbcd0bfe47f54d3e3349e5c381_v2\", \"git\", \"goma\", \"infra_gclient_with_go\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"dev-luci-chromium-ci-win10-0-0y9w\"], \"image\": [\"chrome-win10-1703-20070700-8a9e7990cc1\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5405-e12058b\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "adhoc-testing@luci-token-server-dev.iam.gserviceaccount.com",
"started_ts": "1594673745687481",
"status": "COMPLETED",
"status_changed_ts": "1594674212938345",
"tags": [
"build_address:luci.infra.ci/infra-continuous-win10-64/17263",
"builder:infra-continuous-win10-64",
"buildset:commit/git/db8227dc00e2cb479f142d82ad9287cdf7e072cc",
"buildset:commit/gitiles/chromium.googlesource.com/infra/infra/+/db8227dc00e2cb479f142d82ad9287cdf7e072cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9040290448994511616",
"scheduler_job_id:infra/infra-continuous-win10-64",
"swarming_hostname:chromium-swarm-dev.appspot.com",
"swarming_tag:log_location:logdog://luci-logdog-dev.appspot.com/infra/buildbucket/cr-buildbucket-dev.appspot.com/8874860386912545296/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:infra_continuous",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:4d62980088298910",
"user_agent:luci-scheduler-dev"
],
"updated_ts": "1594674212955496",
"url": "https://luci-milo-dev.appspot.com/b/8874860386912545296",
"utcnow_ts": "1594674217945931"
}
}
[21:03:37.955] Invocation finished in 1h0m9.806377125s with status SUCCEEDED