[21:47:30.180] New invocation is queued and will start shortly
[21:47:31.305] Starting the invocation (attempt 1)
[21:47:31.347] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:47:31.347] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:47:31.347] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9084948208211201024",
"parameters_json": "{\"builder_name\":\"pkg-win-release\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk/\",\"revision\":\"d4ccff7b54e4073084403d77d31727add59b217f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:pkg-win-release",
"scheduler_invocation_id:9084948208211201024",
"scheduler_job_id:dart/pkg-win-release",
"user_agent:luci-scheduler",
"buildset:commit/git/d4ccff7b54e4073084403d77d31727add59b217f",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/d4ccff7b54e4073084403d77d31727add59b217f",
"gitiles_ref:refs/heads/master"
]
}
[21:47:32.897] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552081651480475",
"id": "8919518146176361248",
"lease_expiration_ts": "1552200452302322",
"lease_key": "1130256256",
"parameters_json": "{\"builder_name\": \"pkg-win-release\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk/\", \"revision\": \"d4ccff7b54e4073084403d77d31727add59b217f\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552081652520234",
"tags": [
"build_address:luci.dart.ci.sandbox/pkg-win-release/4429",
"builder:pkg-win-release",
"buildset:commit/git/d4ccff7b54e4073084403d77d31727add59b217f",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/d4ccff7b54e4073084403d77d31727add59b217f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084948208211201024",
"scheduler_job_id:dart/pkg-win-release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8919518146176361248/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4378181143389410",
"user_agent:luci-scheduler"
],
"updated_ts": "1552081652520335",
"url": "https://ci.chromium.org/b/8919518146176361248",
"utcnow_ts": "1552081652885251"
}
}
[21:47:32.897] Task URL: https://ci.chromium.org/b/8919518146176361248
[21:47:32.897] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:2:0) after 1m0s
[21:48:32.880] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:2:0)
[21:48:32.880] Timer tick, asking Buildbucket for the build status
[21:48:32.996] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:48:32.996] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:3:0) after 1m0s
[21:49:33.013] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:3:0)
[21:49:33.013] Timer tick, asking Buildbucket for the build status
[21:49:33.075] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:49:33.075] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:4:0) after 1m0s
[21:50:33.063] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:4:0)
[21:50:33.063] Timer tick, asking Buildbucket for the build status
[21:50:33.246] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:50:33.246] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:5:0) after 1m0s
[21:51:33.265] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:5:0)
[21:51:33.265] Timer tick, asking Buildbucket for the build status
[21:51:33.344] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:33.344] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:6:0) after 1m0s
[21:52:33.363] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:6:0)
[21:52:33.363] Timer tick, asking Buildbucket for the build status
[21:52:33.452] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:33.452] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:7:0) after 1m0s
[21:53:33.461] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:7:0)
[21:53:33.461] Timer tick, asking Buildbucket for the build status
[21:53:33.536] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:33.536] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:8:0) after 1m0s
[21:54:33.552] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:8:0)
[21:54:33.552] Timer tick, asking Buildbucket for the build status
[21:54:33.610] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:33.610] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:9:0) after 1m0s
[21:55:33.640] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:9:0)
[21:55:33.640] Timer tick, asking Buildbucket for the build status
[21:55:33.694] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:55:33.694] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:10:0) after 1m0s
[21:56:33.724] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:10:0)
[21:56:33.724] Timer tick, asking Buildbucket for the build status
[21:56:33.785] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:56:33.785] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:11:0) after 1m0s
[21:57:33.778] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:11:0)
[21:57:33.778] Timer tick, asking Buildbucket for the build status
[21:57:33.893] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:57:33.893] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:12:0) after 1m0s
[21:58:33.904] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:12:0)
[21:58:33.904] Timer tick, asking Buildbucket for the build status
[21:58:33.977] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:58:33.977] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:13:0) after 1m0s
[21:59:33.989] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:13:0)
[21:59:33.989] Timer tick, asking Buildbucket for the build status
[21:59:34.044] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:59:34.044] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:14:0) after 1m0s
[22:00:34.104] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:14:0)
[22:00:34.104] Timer tick, asking Buildbucket for the build status
[22:00:34.194] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:00:34.194] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:15:0) after 1m0s
[22:01:34.208] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:15:0)
[22:01:34.208] Timer tick, asking Buildbucket for the build status
[22:01:34.282] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:01:34.282] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:16:0) after 1m0s
[22:02:34.311] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:16:0)
[22:02:34.311] Timer tick, asking Buildbucket for the build status
[22:02:34.360] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:34.360] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:17:0) after 1m0s
[22:03:34.388] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:17:0)
[22:03:34.388] Timer tick, asking Buildbucket for the build status
[22:03:34.459] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:34.459] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:18:0) after 1m0s
[22:04:34.485] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:18:0)
[22:04:34.485] Timer tick, asking Buildbucket for the build status
[22:04:34.800] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:34.800] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:19:0) after 1m0s
[22:05:34.820] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:19:0)
[22:05:34.820] Timer tick, asking Buildbucket for the build status
[22:05:34.868] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:34.868] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:20:0) after 1m0s
[22:06:34.905] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:20:0)
[22:06:34.905] Timer tick, asking Buildbucket for the build status
[22:06:34.986] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:06:34.986] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:21:0) after 1m0s
[22:07:35.045] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:21:0)
[22:07:35.045] Timer tick, asking Buildbucket for the build status
[22:07:35.105] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:35.105] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:22:0) after 1m0s
[22:08:35.117] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:22:0)
[22:08:35.117] Timer tick, asking Buildbucket for the build status
[22:08:35.181] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:35.181] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:23:0) after 1m0s
[22:09:35.185] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:23:0)
[22:09:35.185] Timer tick, asking Buildbucket for the build status
[22:09:35.442] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:35.442] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:24:0) after 1m0s
[22:10:35.438] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:24:0)
[22:10:35.438] Timer tick, asking Buildbucket for the build status
[22:10:35.502] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:35.502] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:25:0) after 1m0s
[22:11:35.526] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:25:0)
[22:11:35.526] Timer tick, asking Buildbucket for the build status
[22:11:35.626] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:11:35.626] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:26:0) after 1m0s
[22:12:35.686] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:26:0)
[22:12:35.686] Timer tick, asking Buildbucket for the build status
[22:12:35.753] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:12:35.753] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:27:0) after 1m0s
[22:13:35.773] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:27:0)
[22:13:35.773] Timer tick, asking Buildbucket for the build status
[22:13:35.866] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:13:35.866] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:28:0) after 1m0s
[22:14:35.881] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:28:0)
[22:14:35.881] Timer tick, asking Buildbucket for the build status
[22:14:35.957] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:14:35.957] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:29:0) after 1m0s
[22:15:35.947] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:29:0)
[22:15:35.947] Timer tick, asking Buildbucket for the build status
[22:15:36.110] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:15:36.110] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:30:0) after 1m0s
[22:16:36.091] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:30:0)
[22:16:36.091] Timer tick, asking Buildbucket for the build status
[22:16:36.260] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:16:36.260] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:31:0) after 1m0s
[22:17:36.291] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:31:0)
[22:17:36.291] Timer tick, asking Buildbucket for the build status
[22:17:36.450] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:17:36.450] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:32:0) after 1m0s
[22:18:36.488] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:32:0)
[22:18:36.488] Timer tick, asking Buildbucket for the build status
[22:18:36.657] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:18:36.657] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:33:0) after 1m0s
[22:19:36.676] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:33:0)
[22:19:36.676] Timer tick, asking Buildbucket for the build status
[22:19:36.730] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:19:36.730] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:34:0) after 1m0s
[22:20:36.748] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:34:0)
[22:20:36.748] Timer tick, asking Buildbucket for the build status
[22:20:36.917] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:20:36.917] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:35:0) after 1m0s
[22:21:36.939] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:35:0)
[22:21:36.939] Timer tick, asking Buildbucket for the build status
[22:21:36.987] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:21:36.987] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:36:0) after 1m0s
[22:22:37.004] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:36:0)
[22:22:37.004] Timer tick, asking Buildbucket for the build status
[22:22:37.069] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:22:37.069] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:37:0) after 1m0s
[22:23:37.095] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:37:0)
[22:23:37.095] Timer tick, asking Buildbucket for the build status
[22:23:37.155] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:23:37.155] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:38:0) after 1m0s
[22:24:37.178] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:38:0)
[22:24:37.178] Timer tick, asking Buildbucket for the build status
[22:24:37.287] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:24:37.287] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:39:0) after 1m0s
[22:25:37.302] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:39:0)
[22:25:37.302] Timer tick, asking Buildbucket for the build status
[22:25:37.359] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:25:37.359] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:40:0) after 1m0s
[22:26:37.332] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:40:0)
[22:26:37.332] Timer tick, asking Buildbucket for the build status
[22:26:37.448] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:26:37.448] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:41:0) after 1m0s
[22:27:37.442] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:41:0)
[22:27:37.442] Timer tick, asking Buildbucket for the build status
[22:27:37.491] Build 8919518146176361248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:37.491] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:42:0) after 1m0s
[22:28:11.807] Received PubSub notification, asking Buildbucket for the build status
[22:28:11.910] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:37.511] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:42:0)
[22:28:37.511] Timer tick, asking Buildbucket for the build status
[22:28:37.555] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:37.555] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:44:0) after 1m0s
[22:29:37.610] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:44:0)
[22:29:37.610] Timer tick, asking Buildbucket for the build status
[22:29:37.704] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:37.704] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:45:0) after 1m0s
[22:30:37.721] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:45:0)
[22:30:37.721] Timer tick, asking Buildbucket for the build status
[22:30:37.769] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:37.769] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:46:0) after 1m0s
[22:31:37.779] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:46:0)
[22:31:37.779] Timer tick, asking Buildbucket for the build status
[22:31:37.818] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:37.818] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:47:0) after 1m0s
[22:32:37.810] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:47:0)
[22:32:37.810] Timer tick, asking Buildbucket for the build status
[22:32:37.932] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:37.932] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:48:0) after 1m0s
[22:33:37.953] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:48:0)
[22:33:37.953] Timer tick, asking Buildbucket for the build status
[22:33:38.118] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:38.118] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:49:0) after 1m0s
[22:34:38.119] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:49:0)
[22:34:38.119] Timer tick, asking Buildbucket for the build status
[22:34:38.276] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:38.276] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:50:0) after 1m0s
[22:35:38.308] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:50:0)
[22:35:38.308] Timer tick, asking Buildbucket for the build status
[22:35:38.348] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:38.348] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:51:0) after 1m0s
[22:36:38.388] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:51:0)
[22:36:38.388] Timer tick, asking Buildbucket for the build status
[22:36:38.466] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:38.466] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:52:0) after 1m0s
[22:37:38.435] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:52:0)
[22:37:38.435] Timer tick, asking Buildbucket for the build status
[22:37:38.537] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:38.537] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:53:0) after 1m0s
[22:38:38.531] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:53:0)
[22:38:38.531] Timer tick, asking Buildbucket for the build status
[22:38:38.773] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:38.773] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:54:0) after 1m0s
[22:39:38.798] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:54:0)
[22:39:38.798] Timer tick, asking Buildbucket for the build status
[22:39:38.859] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:38.859] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:55:0) after 1m0s
[22:40:38.878] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:55:0)
[22:40:38.878] Timer tick, asking Buildbucket for the build status
[22:40:38.917] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:38.917] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:56:0) after 1m0s
[22:41:38.936] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:56:0)
[22:41:38.936] Timer tick, asking Buildbucket for the build status
[22:41:38.968] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:38.968] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:57:0) after 1m0s
[22:42:38.959] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:57:0)
[22:42:38.959] Timer tick, asking Buildbucket for the build status
[22:42:39.084] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:39.084] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:58:0) after 1m0s
[22:43:39.098] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:58:0)
[22:43:39.098] Timer tick, asking Buildbucket for the build status
[22:43:39.137] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:39.137] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:59:0) after 1m0s
[22:44:39.136] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:59:0)
[22:44:39.136] Timer tick, asking Buildbucket for the build status
[22:44:39.180] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:39.180] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:60:0) after 1m0s
[22:45:39.203] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:60:0)
[22:45:39.203] Timer tick, asking Buildbucket for the build status
[22:45:39.288] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:39.288] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:61:0) after 1m0s
[22:46:39.309] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:61:0)
[22:46:39.309] Timer tick, asking Buildbucket for the build status
[22:46:39.376] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:39.376] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:62:0) after 1m0s
[22:47:39.401] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:62:0)
[22:47:39.401] Timer tick, asking Buildbucket for the build status
[22:47:39.449] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:39.449] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:63:0) after 1m0s
[22:48:39.477] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:63:0)
[22:48:39.477] Timer tick, asking Buildbucket for the build status
[22:48:39.542] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:39.542] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:64:0) after 1m0s
[22:49:39.562] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:64:0)
[22:49:39.562] Timer tick, asking Buildbucket for the build status
[22:49:39.643] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:39.643] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:65:0) after 1m0s
[22:50:39.671] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:65:0)
[22:50:39.671] Timer tick, asking Buildbucket for the build status
[22:50:39.708] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:39.708] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:66:0) after 1m0s
[22:51:39.729] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:66:0)
[22:51:39.729] Timer tick, asking Buildbucket for the build status
[22:51:39.788] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:39.788] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:67:0) after 1m0s
[22:52:39.766] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:67:0)
[22:52:39.766] Timer tick, asking Buildbucket for the build status
[22:52:39.821] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:39.821] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:68:0) after 1m0s
[22:53:39.842] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:68:0)
[22:53:39.842] Timer tick, asking Buildbucket for the build status
[22:53:39.895] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:39.895] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:69:0) after 1m0s
[22:54:39.873] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:69:0)
[22:54:39.873] Timer tick, asking Buildbucket for the build status
[22:54:39.992] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:39.992] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:70:0) after 1m0s
[22:55:39.971] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:70:0)
[22:55:39.971] Timer tick, asking Buildbucket for the build status
[22:55:40.032] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:40.032] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:71:0) after 1m0s
[22:56:40.053] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:71:0)
[22:56:40.053] Timer tick, asking Buildbucket for the build status
[22:56:40.171] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:40.171] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:72:0) after 1m0s
[22:57:40.199] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:72:0)
[22:57:40.199] Timer tick, asking Buildbucket for the build status
[22:57:40.259] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:40.259] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:73:0) after 1m0s
[22:58:40.238] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:73:0)
[22:58:40.238] Timer tick, asking Buildbucket for the build status
[22:58:40.376] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:40.376] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:74:0) after 1m0s
[22:59:40.400] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:74:0)
[22:59:40.400] Timer tick, asking Buildbucket for the build status
[22:59:40.438] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:40.438] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:75:0) after 1m0s
[23:00:40.452] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:75:0)
[23:00:40.452] Timer tick, asking Buildbucket for the build status
[23:00:40.534] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:40.534] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:76:0) after 1m0s
[23:01:40.543] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:76:0)
[23:01:40.543] Timer tick, asking Buildbucket for the build status
[23:01:40.581] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:40.581] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:77:0) after 1m0s
[23:02:40.602] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:77:0)
[23:02:40.602] Timer tick, asking Buildbucket for the build status
[23:02:40.661] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:40.661] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:78:0) after 1m0s
[23:03:40.680] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:78:0)
[23:03:40.680] Timer tick, asking Buildbucket for the build status
[23:03:40.725] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:40.725] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:79:0) after 1m0s
[23:04:40.745] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:79:0)
[23:04:40.745] Timer tick, asking Buildbucket for the build status
[23:04:40.780] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:40.780] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:80:0) after 1m0s
[23:05:40.764] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:80:0)
[23:05:40.764] Timer tick, asking Buildbucket for the build status
[23:05:40.875] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:40.875] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:81:0) after 1m0s
[23:06:40.890] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:81:0)
[23:06:40.890] Timer tick, asking Buildbucket for the build status
[23:06:40.941] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:40.941] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:82:0) after 1m0s
[23:07:40.942] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:82:0)
[23:07:40.942] Timer tick, asking Buildbucket for the build status
[23:07:40.976] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:40.976] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:83:0) after 1m0s
[23:08:40.987] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:83:0)
[23:08:40.987] Timer tick, asking Buildbucket for the build status
[23:08:41.092] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:41.092] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:84:0) after 1m0s
[23:09:41.112] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:84:0)
[23:09:41.112] Timer tick, asking Buildbucket for the build status
[23:09:41.164] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:41.164] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:85:0) after 1m0s
[23:10:41.184] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:85:0)
[23:10:41.184] Timer tick, asking Buildbucket for the build status
[23:10:41.284] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:41.284] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:86:0) after 1m0s
[23:11:41.285] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:86:0)
[23:11:41.285] Timer tick, asking Buildbucket for the build status
[23:11:41.332] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:41.332] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:87:0) after 1m0s
[23:12:41.357] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:87:0)
[23:12:41.357] Timer tick, asking Buildbucket for the build status
[23:12:41.659] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:41.659] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:88:0) after 1m0s
[23:13:41.679] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:88:0)
[23:13:41.679] Timer tick, asking Buildbucket for the build status
[23:13:41.767] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:41.767] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:89:0) after 1m0s
[23:14:41.790] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:89:0)
[23:14:41.790] Timer tick, asking Buildbucket for the build status
[23:14:41.961] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:41.961] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:90:0) after 1m0s
[23:15:42.072] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:90:0)
[23:15:42.072] Timer tick, asking Buildbucket for the build status
[23:15:42.315] Build 8919518146176361248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:42.315] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9084948208211201024:91:0) after 1m0s
[23:15:43.017] Received PubSub notification, asking Buildbucket for the build status
[23:15:43.069] Build 8919518146176361248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:15:43.069] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "AUTO",
"completed_ts": "1552086942030504",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552081651480475",
"id": "8919518146176361248",
"parameters_json": "{\"builder_name\": \"pkg-win-release\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk/\", \"revision\": \"d4ccff7b54e4073084403d77d31727add59b217f\"}}",
"project": "dart",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4158-1053c58\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"win10-dart-fedce810-us-central1-c-587j\"], \"pool\": [\"dart.tests\", \"luci.dart.try\"], \"caches\": [\"builder_1c0ef31f5f8d1f18f7fd8c84ad96f89f648d151bb815f45fcb9371a45c954f04_v2\", \"builder_41d4f311fffe347e4c89ecacc33c29416724629c26dd89895263cad5fc8b9a10_v2\", \"builder_52304b3d18db17d8e1578a10f4aad442eeba949dbab2c7b3531ef01be5acfde1_v2\", \"builder_5f81889e5cc046c508695b9feacda728e9553820578223d9e12af68706aa42b8_v2\", \"git\", \"goma_v2\", \"swarming_module_cache_vpython\", \"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"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}, \"repository\": \"https://dart.googlesource.com/sdk/\", \"buildername\": \"pkg-win-release\", \"new_workflow_enabled\": true, \"got_revision\": \"d4ccff7b54e4073084403d77d31727add59b217f\", \"recipe\": \"dart/neo\", \"mastername\": \"client.dart\", \"clobber\": \"true\", \"buildnumber\": 4429, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"d4ccff7b54e4073084403d77d31727add59b217f\", \"bot_id\": \"win10-dart-fedce810-us-central1-c-587j\"}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1552084091160382",
"status": "COMPLETED",
"status_changed_ts": "1552086942698372",
"tags": [
"build_address:luci.dart.ci.sandbox/pkg-win-release/4429",
"builder:pkg-win-release",
"buildset:commit/git/d4ccff7b54e4073084403d77d31727add59b217f",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/d4ccff7b54e4073084403d77d31727add59b217f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084948208211201024",
"scheduler_job_id:dart/pkg-win-release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8919518146176361248/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4378181143389410",
"user_agent:luci-scheduler"
],
"updated_ts": "1552086942703881",
"url": "https://ci.chromium.org/b/8919518146176361248",
"utcnow_ts": "1552086943060818"
}
}
[23:15:43.069] Invocation finished in 1h28m12.913978711s with status SUCCEEDED