[20:21:14.722] New invocation is queued and will start shortly
[20:21:16.014] Starting the invocation (attempt 1)
[20:21:16.062] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:21:16.062] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:21:16.062] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9087127962259554096",
"parameters_json": "{\"builder_name\":\"pkg-win-release\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk/\",\"revision\":\"9d280198a4d8a8389e3467128fdd6421e70694b7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:pkg-win-release",
"scheduler_invocation_id:9087127962259554096",
"scheduler_job_id:dart/pkg-win-release",
"user_agent:luci-scheduler",
"buildset:commit/git/9d280198a4d8a8389e3467128fdd6421e70694b7",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/9d280198a4d8a8389e3467128fdd6421e70694b7",
"gitiles_ref:refs/heads/master"
]
}
[20:21:17.954] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550002876205261",
"id": "8921697900038973296",
"lease_expiration_ts": "1550121677366465",
"lease_key": "1562968278",
"parameters_json": "{\"builder_name\": \"pkg-win-release\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk/\", \"revision\": \"9d280198a4d8a8389e3467128fdd6421e70694b7\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550002877567606",
"tags": [
"build_address:luci.dart.ci.sandbox/pkg-win-release/4113",
"builder:pkg-win-release",
"buildset:commit/git/9d280198a4d8a8389e3467128fdd6421e70694b7",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/9d280198a4d8a8389e3467128fdd6421e70694b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087127962259554096",
"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/8921697900038973296/+/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:42fc3079ea628610",
"user_agent:luci-scheduler"
],
"updated_ts": "1550002877567759",
"url": "https://ci.chromium.org/b/8921697900038973296",
"utcnow_ts": "1550002877943694"
}
}
[20:21:17.954] Task URL: https://ci.chromium.org/b/8921697900038973296
[20:21:17.954] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:2:0) after 1m0s
[20:22:17.973] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:2:0)
[20:22:17.974] Timer tick, asking Buildbucket for the build status
[20:22:18.355] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:18.355] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:3:0) after 1m0s
[20:23:18.371] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:3:0)
[20:23:18.371] Timer tick, asking Buildbucket for the build status
[20:23:18.472] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:18.472] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:4:0) after 1m0s
[20:24:18.504] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:4:0)
[20:24:18.504] Timer tick, asking Buildbucket for the build status
[20:24:18.576] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:24:18.576] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:5:0) after 1m0s
[20:25:18.598] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:5:0)
[20:25:18.598] Timer tick, asking Buildbucket for the build status
[20:25:18.805] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:25:18.805] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:6:0) after 1m0s
[20:26:18.823] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:6:0)
[20:26:18.823] Timer tick, asking Buildbucket for the build status
[20:26:18.971] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:26:18.972] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:7:0) after 1m0s
[20:27:19.016] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:7:0)
[20:27:19.016] Timer tick, asking Buildbucket for the build status
[20:27:19.392] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:27:19.392] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:8:0) after 1m0s
[20:28:19.411] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:8:0)
[20:28:19.411] Timer tick, asking Buildbucket for the build status
[20:28:20.259] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:28:20.259] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:9:0) after 1m0s
[20:29:20.278] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:9:0)
[20:29:20.278] Timer tick, asking Buildbucket for the build status
[20:29:20.334] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:29:20.334] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:10:0) after 1m0s
[20:30:20.352] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:10:0)
[20:30:20.352] Timer tick, asking Buildbucket for the build status
[20:30:20.565] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:30:20.565] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:11:0) after 1m0s
[20:31:20.585] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:11:0)
[20:31:20.585] Timer tick, asking Buildbucket for the build status
[20:31:21.212] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:31:21.212] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:12:0) after 1m0s
[20:32:21.229] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:12:0)
[20:32:21.229] Timer tick, asking Buildbucket for the build status
[20:32:21.366] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:32:21.366] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:13:0) after 1m0s
[20:33:21.385] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:13:0)
[20:33:21.385] Timer tick, asking Buildbucket for the build status
[20:33:21.447] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:33:21.447] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:14:0) after 1m0s
[20:34:21.469] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:14:0)
[20:34:21.469] Timer tick, asking Buildbucket for the build status
[20:34:21.621] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:34:21.621] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:15:0) after 1m0s
[20:35:21.641] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:15:0)
[20:35:21.641] Timer tick, asking Buildbucket for the build status
[20:35:21.726] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:35:21.726] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:16:0) after 1m0s
[20:36:21.743] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:16:0)
[20:36:21.743] Timer tick, asking Buildbucket for the build status
[20:36:21.825] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:21.825] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:17:0) after 1m0s
[20:37:21.842] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:17:0)
[20:37:21.842] Timer tick, asking Buildbucket for the build status
[20:37:21.900] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:21.900] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:18:0) after 1m0s
[20:38:21.972] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:18:0)
[20:38:21.972] Timer tick, asking Buildbucket for the build status
[20:38:22.410] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:22.410] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:19:0) after 1m0s
[20:39:22.431] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:19:0)
[20:39:22.431] Timer tick, asking Buildbucket for the build status
[20:39:22.667] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:22.667] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:20:0) after 1m0s
[20:40:22.689] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:20:0)
[20:40:22.689] Timer tick, asking Buildbucket for the build status
[20:40:23.016] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:40:23.016] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:21:0) after 1m0s
[20:41:23.036] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:21:0)
[20:41:23.036] Timer tick, asking Buildbucket for the build status
[20:41:23.094] Build 8921697900038973296: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:41:23.094] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:22:0) after 1m0s
[20:41:35.731] Received PubSub notification, asking Buildbucket for the build status
[20:41:36.071] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:23.116] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:22:0)
[20:42:23.116] Timer tick, asking Buildbucket for the build status
[20:42:23.279] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:23.279] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:24:0) after 1m0s
[20:43:23.296] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:24:0)
[20:43:23.296] Timer tick, asking Buildbucket for the build status
[20:43:23.468] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:23.468] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:25:0) after 1m0s
[20:44:23.490] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:25:0)
[20:44:23.490] Timer tick, asking Buildbucket for the build status
[20:44:23.601] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:23.601] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:26:0) after 1m0s
[20:45:23.682] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:26:0)
[20:45:23.682] Timer tick, asking Buildbucket for the build status
[20:45:23.809] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:23.809] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:27:0) after 1m0s
[20:46:23.826] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:27:0)
[20:46:23.826] Timer tick, asking Buildbucket for the build status
[20:46:23.981] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:23.981] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:28:0) after 1m0s
[20:47:24.003] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:28:0)
[20:47:24.003] Timer tick, asking Buildbucket for the build status
[20:47:24.044] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:24.044] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:29:0) after 1m0s
[20:48:24.075] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:29:0)
[20:48:24.075] Timer tick, asking Buildbucket for the build status
[20:48:24.237] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:24.237] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:30:0) after 1m0s
[20:49:24.264] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:30:0)
[20:49:24.265] Timer tick, asking Buildbucket for the build status
[20:49:24.461] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:24.461] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:31:0) after 1m0s
[20:50:24.493] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:31:0)
[20:50:24.493] Timer tick, asking Buildbucket for the build status
[20:50:24.580] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:24.580] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:32:0) after 1m0s
[20:51:24.690] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:32:0)
[20:51:24.690] Timer tick, asking Buildbucket for the build status
[20:51:24.911] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:24.911] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:33:0) after 1m0s
[20:52:24.932] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:33:0)
[20:52:24.932] Timer tick, asking Buildbucket for the build status
[20:52:25.040] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:25.040] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:34:0) after 1m0s
[20:53:25.059] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:34:0)
[20:53:25.059] Timer tick, asking Buildbucket for the build status
[20:53:25.110] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:25.110] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:35:0) after 1m0s
[20:54:25.129] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:35:0)
[20:54:25.129] Timer tick, asking Buildbucket for the build status
[20:54:25.202] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:25.202] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:36:0) after 1m0s
[20:55:25.221] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:36:0)
[20:55:25.221] Timer tick, asking Buildbucket for the build status
[20:55:25.319] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:25.319] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:37:0) after 1m0s
[20:56:25.336] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:37:0)
[20:56:25.336] Timer tick, asking Buildbucket for the build status
[20:56:25.432] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:25.432] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:38:0) after 1m0s
[20:57:25.452] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:38:0)
[20:57:25.452] Timer tick, asking Buildbucket for the build status
[20:57:25.849] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:25.849] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:39:0) after 1m0s
[20:58:25.888] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:39:0)
[20:58:25.888] Timer tick, asking Buildbucket for the build status
[20:58:26.078] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:26.078] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:40:0) after 1m0s
[20:59:26.100] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:40:0)
[20:59:26.100] Timer tick, asking Buildbucket for the build status
[20:59:26.229] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:26.229] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:41:0) after 1m0s
[21:00:26.250] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:41:0)
[21:00:26.250] Timer tick, asking Buildbucket for the build status
[21:00:26.325] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:26.325] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:42:0) after 1m0s
[21:01:26.358] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:42:0)
[21:01:26.358] Timer tick, asking Buildbucket for the build status
[21:01:26.432] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:26.432] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:43:0) after 1m0s
[21:02:26.454] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:43:0)
[21:02:26.454] Timer tick, asking Buildbucket for the build status
[21:02:26.678] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:26.678] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:44:0) after 1m0s
[21:03:26.703] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:44:0)
[21:03:26.703] Timer tick, asking Buildbucket for the build status
[21:03:26.824] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:26.824] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:45:0) after 1m0s
[21:04:26.866] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:45:0)
[21:04:26.866] Timer tick, asking Buildbucket for the build status
[21:04:26.913] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:26.913] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:46:0) after 1m0s
[21:05:26.935] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:46:0)
[21:05:26.935] Timer tick, asking Buildbucket for the build status
[21:05:27.227] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:27.227] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:47:0) after 1m0s
[21:06:27.321] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:47:0)
[21:06:27.321] Timer tick, asking Buildbucket for the build status
[21:06:27.491] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:27.491] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:48:0) after 1m0s
[21:07:27.543] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:48:0)
[21:07:27.543] Timer tick, asking Buildbucket for the build status
[21:07:27.712] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:27.712] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:49:0) after 1m0s
[21:08:27.773] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:49:0)
[21:08:27.773] Timer tick, asking Buildbucket for the build status
[21:08:27.860] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:27.860] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:50:0) after 1m0s
[21:09:27.877] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:50:0)
[21:09:27.877] Timer tick, asking Buildbucket for the build status
[21:09:27.940] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:27.940] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:51:0) after 1m0s
[21:10:27.964] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:51:0)
[21:10:27.964] Timer tick, asking Buildbucket for the build status
[21:10:28.047] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:28.048] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:52:0) after 1m0s
[21:11:28.067] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:52:0)
[21:11:28.067] Timer tick, asking Buildbucket for the build status
[21:11:28.141] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:28.141] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:53:0) after 1m0s
[21:12:28.161] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:53:0)
[21:12:28.161] Timer tick, asking Buildbucket for the build status
[21:12:28.278] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:28.278] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:54:0) after 1m0s
[21:13:28.350] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:54:0)
[21:13:28.350] Timer tick, asking Buildbucket for the build status
[21:13:28.397] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:28.397] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:55:0) after 1m0s
[21:14:28.420] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:55:0)
[21:14:28.420] Timer tick, asking Buildbucket for the build status
[21:14:28.562] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:28.562] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:56:0) after 1m0s
[21:15:28.581] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:56:0)
[21:15:28.581] Timer tick, asking Buildbucket for the build status
[21:15:28.985] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:28.985] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:57:0) after 1m0s
[21:16:29.036] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:57:0)
[21:16:29.036] Timer tick, asking Buildbucket for the build status
[21:16:29.218] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:29.218] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:58:0) after 1m0s
[21:17:29.329] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:58:0)
[21:17:29.329] Timer tick, asking Buildbucket for the build status
[21:17:29.577] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:29.577] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:59:0) after 1m0s
[21:18:29.616] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:59:0)
[21:18:29.616] Timer tick, asking Buildbucket for the build status
[21:18:30.063] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:30.063] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:60:0) after 1m0s
[21:19:30.144] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:60:0)
[21:19:30.144] Timer tick, asking Buildbucket for the build status
[21:19:30.262] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:30.263] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:61:0) after 1m0s
[21:20:30.340] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:61:0)
[21:20:30.340] Timer tick, asking Buildbucket for the build status
[21:20:30.851] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:30.851] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:62:0) after 1m0s
[21:21:30.873] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:62:0)
[21:21:30.873] Timer tick, asking Buildbucket for the build status
[21:21:30.926] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:30.926] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:63:0) after 1m0s
[21:22:30.916] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:63:0)
[21:22:30.916] Timer tick, asking Buildbucket for the build status
[21:22:30.996] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:30.996] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:64:0) after 1m0s
[21:23:31.020] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:64:0)
[21:23:31.020] Timer tick, asking Buildbucket for the build status
[21:23:31.918] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:31.918] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:65:0) after 1m0s
[21:24:32.019] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:65:0)
[21:24:32.019] Timer tick, asking Buildbucket for the build status
[21:24:32.786] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:32.786] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:66:0) after 1m0s
[21:25:32.813] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:66:0)
[21:25:32.813] Timer tick, asking Buildbucket for the build status
[21:25:33.361] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:33.361] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:67:0) after 1m0s
[21:26:33.384] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:67:0)
[21:26:33.384] Timer tick, asking Buildbucket for the build status
[21:26:33.957] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:33.957] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:68:0) after 1m0s
[21:27:34.016] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:68:0)
[21:27:34.016] Timer tick, asking Buildbucket for the build status
[21:27:34.473] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:34.473] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:69:0) after 1m0s
[21:28:34.511] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:69:0)
[21:28:34.511] Timer tick, asking Buildbucket for the build status
[21:28:34.889] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:34.889] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:70:0) after 1m0s
[21:29:34.920] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:70:0)
[21:29:34.920] Timer tick, asking Buildbucket for the build status
[21:29:35.296] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:35.296] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:71:0) after 1m0s
[21:30:35.316] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:71:0)
[21:30:35.316] Timer tick, asking Buildbucket for the build status
[21:30:35.543] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:35.543] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:72:0) after 1m0s
[21:31:35.572] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:72:0)
[21:31:35.572] Timer tick, asking Buildbucket for the build status
[21:31:35.845] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:35.845] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:73:0) after 1m0s
[21:32:35.867] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:73:0)
[21:32:35.867] Timer tick, asking Buildbucket for the build status
[21:32:35.970] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:35.970] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:74:0) after 1m0s
[21:33:35.997] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:74:0)
[21:33:35.997] Timer tick, asking Buildbucket for the build status
[21:33:36.149] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:36.149] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:75:0) after 1m0s
[21:34:36.171] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:75:0)
[21:34:36.171] Timer tick, asking Buildbucket for the build status
[21:34:36.788] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:36.788] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:76:0) after 1m0s
[21:35:36.813] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:76:0)
[21:35:36.813] Timer tick, asking Buildbucket for the build status
[21:35:36.918] Build 8921697900038973296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:36.918] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9087127962259554096:77:0) after 1m0s
[21:36:28.644] Received PubSub notification, asking Buildbucket for the build status
[21:36:28.857] Build 8921697900038973296: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:36:28.857] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "AUTO",
"completed_ts": "1550007384371925",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550002876205261",
"failure_reason": "BUILD_FAILURE",
"id": "8921697900038973296",
"parameters_json": "{\"builder_name\": \"pkg-win-release\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk/\", \"revision\": \"9d280198a4d8a8389e3467128fdd6421e70694b7\"}}",
"project": "dart",
"result": "FAILURE",
"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\": [\"4085-c81638b\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"win10-dart-fedce810-us-central1-c-4vv6\"], \"pool\": [\"dart.tests\", \"luci.dart.try\"], \"caches\": [\"swarming_module_cache_vpython\"]}}, \"ui\": {\"info\": \"Failure test results\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"buildnumber\": 4113, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"pkg-win-release\", \"key\": \"builder\"}, {\"value\": \"commit/git/9d280198a4d8a8389e3467128fdd6421e70694b7\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/dart.googlesource.com/sdk/+/9d280198a4d8a8389e3467128fdd6421e70694b7\", \"key\": \"buildset\"}, {\"value\": \"9087127962259554096\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"dart/pkg-win-release\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"dart\", \"builder\": \"pkg-win-release\", \"bucket\": \"ci.sandbox\"}, \"number\": 4113, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"2acf9ddf085124300131f0a719a64564504c97a6\"}, \"recipe\": {\"name\": \"dart/neo\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921697900038973296\", \"input\": {\"gitilesCommit\": {\"project\": \"sdk\", \"host\": \"dart.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"9d280198a4d8a8389e3467128fdd6421e70694b7\"}}, \"createTime\": \"2019-02-12T20:21:16.205261Z\"}}, \"repository\": \"https://dart.googlesource.com/sdk/\", \"buildername\": \"pkg-win-release\", \"new_workflow_enabled\": true, \"got_revision\": \"9d280198a4d8a8389e3467128fdd6421e70694b7\", \"recipe\": \"dart/neo\", \"mastername\": \"client.dart\", \"clobber\": \"true\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550002876205261, \"tags\": [\"build_address:luci.dart.ci.sandbox/pkg-win-release/4113\", \"builder:pkg-win-release\", \"buildset:commit/git/9d280198a4d8a8389e3467128fdd6421e70694b7\", \"buildset:commit/gitiles/dart.googlesource.com/sdk/+/9d280198a4d8a8389e3467128fdd6421e70694b7\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9087127962259554096\", \"scheduler_job_id:dart/pkg-win-release\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.dart.ci.sandbox\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"dart\", \"id\": \"8921697900038973296\"}}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"9d280198a4d8a8389e3467128fdd6421e70694b7\", \"bot_id\": \"win10-dart-fedce810-us-central1-c-4vv6\"}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1550004094868052",
"status": "COMPLETED",
"status_changed_ts": "1550007388087363",
"tags": [
"build_address:luci.dart.ci.sandbox/pkg-win-release/4113",
"builder:pkg-win-release",
"buildset:commit/git/9d280198a4d8a8389e3467128fdd6421e70694b7",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/9d280198a4d8a8389e3467128fdd6421e70694b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9087127962259554096",
"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/8921697900038973296/+/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:42fc3079ea628610",
"user_agent:luci-scheduler"
],
"updated_ts": "1550007388090932",
"url": "https://ci.chromium.org/b/8921697900038973296",
"utcnow_ts": "1550007388847507"
}
}
[21:36:28.857] Invocation finished in 1h15m14.152833199s with status FAILED