[22:41:11.766] New invocation is queued and will start shortly
[22:41:13.794] Starting the invocation (attempt 1)
[22:41:13.831] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:41:13.831] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:41:13.831] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9031039635116982448",
"parameters_json": "{\"builder_name\":\"analyzer-win-release\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://dart.googlesource.com/sdk\",\"revision\":\"264730e28de5c10571861c3adaca5df50447f7d4\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@264730e28de5c10571861c3adaca5df50447f7d4\",\"title\":\"264730e28de5c10571861c3adaca5df50447f7d4\",\"url\":\"https://dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk\",\"revision\":\"264730e28de5c10571861c3adaca5df50447f7d4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:analyzer-win-release",
"scheduler_invocation_id:9031039635116982448",
"scheduler_job_id:dart/analyzer-win-release",
"user_agent:luci-scheduler",
"buildset:commit/git/264730e28de5c10571861c3adaca5df50447f7d4",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4",
"gitiles_ref:refs/heads/master"
]
}
[22:41:14.565] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:dart",
"created_ts": "1603492873922846",
"id": "8865609572192126336",
"parameters_json": "{\"builder_name\": \"analyzer-win-release\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@264730e28de5c10571861c3adaca5df50447f7d4\", \"title\": \"264730e28de5c10571861c3adaca5df50447f7d4\", \"url\": \"https://dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603492874335042",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-win-release/12629",
"builder:analyzer-win-release",
"buildset:commit/git/264730e28de5c10571861c3adaca5df50447f7d4",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031039635116982448",
"scheduler_job_id:dart/analyzer-win-release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8865609572192126336/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:dart/recipe_bundles/dart.googlesource.com/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1603492874335191",
"url": "https://ci.chromium.org/b/8865609572192126336",
"utcnow_ts": "1603492874556618"
}
}
[22:41:14.565] Task URL: https://ci.chromium.org/b/8865609572192126336
[22:41:14.565] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:2:0) after 1m0s
[22:42:14.541] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:2:0)
[22:42:14.541] Timer tick, asking Buildbucket for the build status
[22:42:14.768] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:14.768] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:3:0) after 1m0s
[22:43:14.836] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:3:0)
[22:43:14.836] Timer tick, asking Buildbucket for the build status
[22:43:15.139] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:15.139] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:4:0) after 1m0s
[22:44:15.297] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:4:0)
[22:44:15.297] Timer tick, asking Buildbucket for the build status
[22:44:15.395] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:15.395] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:5:0) after 1m0s
[22:45:15.492] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:5:0)
[22:45:15.492] Timer tick, asking Buildbucket for the build status
[22:45:15.610] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:15.610] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:6:0) after 1m0s
[22:46:15.776] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:6:0)
[22:46:15.776] Timer tick, asking Buildbucket for the build status
[22:46:15.870] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:15.870] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:7:0) after 1m0s
[22:47:16.051] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:7:0)
[22:47:16.051] Timer tick, asking Buildbucket for the build status
[22:47:16.187] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:16.187] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:8:0) after 1m0s
[22:48:16.289] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:8:0)
[22:48:16.289] Timer tick, asking Buildbucket for the build status
[22:48:16.782] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:16.782] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:9:0) after 1m0s
[22:49:16.881] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:9:0)
[22:49:16.881] Timer tick, asking Buildbucket for the build status
[22:49:17.521] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:17.521] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:10:0) after 1m0s
[22:50:17.675] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:10:0)
[22:50:17.675] Timer tick, asking Buildbucket for the build status
[22:50:17.759] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:17.759] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:11:0) after 1m0s
[22:51:17.852] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:11:0)
[22:51:17.852] Timer tick, asking Buildbucket for the build status
[22:51:17.956] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:17.956] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:12:0) after 1m0s
[22:52:18.061] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:12:0)
[22:52:18.061] Timer tick, asking Buildbucket for the build status
[22:52:18.197] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:18.197] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:13:0) after 1m0s
[22:53:18.235] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:13:0)
[22:53:18.235] Timer tick, asking Buildbucket for the build status
[22:53:18.474] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:18.474] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:14:0) after 1m0s
[22:54:18.571] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:14:0)
[22:54:18.571] Timer tick, asking Buildbucket for the build status
[22:54:18.821] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:18.821] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:15:0) after 1m0s
[22:55:18.830] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:15:0)
[22:55:18.830] Timer tick, asking Buildbucket for the build status
[22:55:18.929] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:18.929] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:16:0) after 1m0s
[22:56:18.950] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:16:0)
[22:56:18.950] Timer tick, asking Buildbucket for the build status
[22:56:19.094] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:19.095] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:17:0) after 1m0s
[22:57:19.095] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:17:0)
[22:57:19.095] Timer tick, asking Buildbucket for the build status
[22:57:19.319] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:19.319] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:18:0) after 1m0s
[22:58:19.404] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:18:0)
[22:58:19.404] Timer tick, asking Buildbucket for the build status
[22:58:19.487] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:19.487] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:19:0) after 1m0s
[22:59:19.502] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:19:0)
[22:59:19.502] Timer tick, asking Buildbucket for the build status
[22:59:19.785] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:19.785] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:20:0) after 1m0s
[23:00:19.882] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:20:0)
[23:00:19.882] Timer tick, asking Buildbucket for the build status
[23:00:20.101] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:20.101] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:21:0) after 1m0s
[23:01:20.138] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:21:0)
[23:01:20.138] Timer tick, asking Buildbucket for the build status
[23:01:20.302] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:20.302] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:22:0) after 1m0s
[23:02:20.323] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:22:0)
[23:02:20.323] Timer tick, asking Buildbucket for the build status
[23:02:20.403] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:20.403] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:23:0) after 1m0s
[23:03:20.425] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:23:0)
[23:03:20.425] Timer tick, asking Buildbucket for the build status
[23:03:20.509] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:20.509] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:24:0) after 1m0s
[23:04:20.557] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:24:0)
[23:04:20.557] Timer tick, asking Buildbucket for the build status
[23:04:20.642] Build 8865609572192126336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:20.642] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:25:0) after 1m0s
[23:05:20.113] Received PubSub notification, asking Buildbucket for the build status
[23:05:20.189] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:20.659] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:25:0)
[23:05:20.659] Timer tick, asking Buildbucket for the build status
[23:05:20.738] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:20.738] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:27:0) after 1m0s
[23:06:20.838] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:27:0)
[23:06:20.838] Timer tick, asking Buildbucket for the build status
[23:06:20.956] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:20.956] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:28:0) after 1m0s
[23:07:21.056] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:28:0)
[23:07:21.056] Timer tick, asking Buildbucket for the build status
[23:07:21.147] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:21.147] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:29:0) after 1m0s
[23:08:21.218] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:29:0)
[23:08:21.218] Timer tick, asking Buildbucket for the build status
[23:08:21.778] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:21.778] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:30:0) after 1m0s
[23:09:21.806] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:30:0)
[23:09:21.806] Timer tick, asking Buildbucket for the build status
[23:09:21.939] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:21.939] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:31:0) after 1m0s
[23:10:22.033] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:31:0)
[23:10:22.033] Timer tick, asking Buildbucket for the build status
[23:10:22.129] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:22.129] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:32:0) after 1m0s
[23:11:22.232] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:32:0)
[23:11:22.232] Timer tick, asking Buildbucket for the build status
[23:11:22.372] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:22.372] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:33:0) after 1m0s
[23:12:22.574] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:33:0)
[23:12:22.574] Timer tick, asking Buildbucket for the build status
[23:12:23.144] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:23.144] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:34:0) after 1m0s
[23:13:23.227] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:34:0)
[23:13:23.227] Timer tick, asking Buildbucket for the build status
[23:13:23.284] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:23.284] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:35:0) after 1m0s
[23:14:23.388] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:35:0)
[23:14:23.388] Timer tick, asking Buildbucket for the build status
[23:14:24.320] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:24.320] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:36:0) after 1m0s
[23:15:24.367] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:36:0)
[23:15:24.367] Timer tick, asking Buildbucket for the build status
[23:15:24.511] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:24.511] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:37:0) after 1m0s
[23:16:24.508] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:37:0)
[23:16:24.508] Timer tick, asking Buildbucket for the build status
[23:16:24.778] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:24.779] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:38:0) after 1m0s
[23:17:24.811] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:38:0)
[23:17:24.811] Timer tick, asking Buildbucket for the build status
[23:17:25.494] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:25.494] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:39:0) after 1m0s
[23:18:25.598] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:39:0)
[23:18:25.598] Timer tick, asking Buildbucket for the build status
[23:18:25.669] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:25.669] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:40:0) after 1m0s
[23:19:25.843] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:40:0)
[23:19:25.843] Timer tick, asking Buildbucket for the build status
[23:19:26.226] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:26.226] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:41:0) after 1m0s
[23:20:26.403] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:41:0)
[23:20:26.403] Timer tick, asking Buildbucket for the build status
[23:20:26.473] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:26.473] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:42:0) after 1m0s
[23:21:26.556] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:42:0)
[23:21:26.556] Timer tick, asking Buildbucket for the build status
[23:21:26.775] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:26.775] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:43:0) after 1m0s
[23:22:26.900] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:43:0)
[23:22:26.900] Timer tick, asking Buildbucket for the build status
[23:22:27.235] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:27.235] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:44:0) after 1m0s
[23:23:27.449] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:44:0)
[23:23:27.449] Timer tick, asking Buildbucket for the build status
[23:23:28.140] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:28.140] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:45:0) after 1m0s
[23:24:28.160] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:45:0)
[23:24:28.160] Timer tick, asking Buildbucket for the build status
[23:24:28.524] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:28.524] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:46:0) after 1m0s
[23:25:28.630] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:46:0)
[23:25:28.630] Timer tick, asking Buildbucket for the build status
[23:25:28.697] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:28.697] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:47:0) after 1m0s
[23:26:28.739] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:47:0)
[23:26:28.739] Timer tick, asking Buildbucket for the build status
[23:26:28.847] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:28.847] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:48:0) after 1m0s
[23:27:28.905] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:48:0)
[23:27:28.905] Timer tick, asking Buildbucket for the build status
[23:27:28.971] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:28.971] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:49:0) after 1m0s
[23:28:29.036] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:49:0)
[23:28:29.036] Timer tick, asking Buildbucket for the build status
[23:28:29.105] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:29.105] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:50:0) after 1m0s
[23:29:29.213] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:50:0)
[23:29:29.213] Timer tick, asking Buildbucket for the build status
[23:29:29.283] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:29.283] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:51:0) after 1m0s
[23:30:29.432] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:51:0)
[23:30:29.432] Timer tick, asking Buildbucket for the build status
[23:30:29.508] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:29.508] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:52:0) after 1m0s
[23:31:29.558] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:52:0)
[23:31:29.558] Timer tick, asking Buildbucket for the build status
[23:31:29.753] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:29.753] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:53:0) after 1m0s
[23:32:29.796] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:53:0)
[23:32:29.796] Timer tick, asking Buildbucket for the build status
[23:32:29.875] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:29.875] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:54:0) after 1m0s
[23:33:29.900] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:54:0)
[23:33:29.900] Timer tick, asking Buildbucket for the build status
[23:33:29.980] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:29.980] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:55:0) after 1m0s
[23:34:30.090] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:55:0)
[23:34:30.090] Timer tick, asking Buildbucket for the build status
[23:34:30.315] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:30.315] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:56:0) after 1m0s
[23:35:30.335] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:56:0)
[23:35:30.335] Timer tick, asking Buildbucket for the build status
[23:35:30.717] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:30.717] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:57:0) after 1m0s
[23:36:30.972] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:57:0)
[23:36:30.972] Timer tick, asking Buildbucket for the build status
[23:36:31.114] Build 8865609572192126336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:31.114] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9031039635116982448:58:0) after 1m0s
[23:37:27.836] Received PubSub notification, asking Buildbucket for the build status
[23:37:27.916] Build 8865609572192126336: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[23:37:27.916] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1603496246978024",
"created_by": "project:dart",
"created_ts": "1603492873922846",
"failure_reason": "INFRA_FAILURE",
"id": "8865609572192126336",
"parameters_json": "{\"builder_name\": \"analyzer-win-release\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@264730e28de5c10571861c3adaca5df50447f7d4\", \"title\": \"264730e28de5c10571861c3adaca5df50447f7d4\", \"url\": \"https://dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\"}}",
"project": "dart",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"recipe infra failure: 1 out of 5 aggregated steps failed: Infra Failure: Step('postprocess_for_goma.upload_log') (timeout)\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@264730e28de5c10571861c3adaca5df50447f7d4\", \"title\": \"264730e28de5c10571861c3adaca5df50447f7d4\", \"url\": \"https://dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4\"}]}, \"$recipe_engine/swarming\": {\"server\": \"https://chromium-swarm.appspot.com\"}, \"bot_id\": \"luci-dart-try-win10-16-2l47\", \"branch\": \"refs/heads/master\", \"buildername\": \"analyzer-win-release\", \"buildnumber\": 12629, \"clobber\": \"true\", \"got_revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\", \"path_config\": \"generic\", \"recipe\": \"dart/neo\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"264730e28de5c10571861c3adaca5df50447f7d4\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"browsers\", \"builder_148cfedf85f034438764d46098ca39be15c5a78077600d642d6f6a78998f136a_v2\", \"builder_4d7e5e8c4d8aac3af5a77a896b149cefe658b009c8d3a3560991feb9a9bf73ee_v2\", \"builder_d6f396784d1bacfeb26d12c7d2f46eacb6fe10d26ab756565a33004b6afc6592_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"dart-ci\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-dart-try-win10-16-2l47\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"dart.tests\", \"luci.dart.try\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}, \"ui\": {\"info\": \"recipe infra failure: 1 out of 5 aggregated steps failed: Infra Failure: Step('postprocess_for_goma.upload_log') (timeout)\"}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1603494319279601",
"status": "COMPLETED",
"status_changed_ts": "1603494319604012",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-win-release/12629",
"builder:analyzer-win-release",
"buildset:commit/git/264730e28de5c10571861c3adaca5df50447f7d4",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/264730e28de5c10571861c3adaca5df50447f7d4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031039635116982448",
"scheduler_job_id:dart/analyzer-win-release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8865609572192126336/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:dart/recipe_bundles/dart.googlesource.com/recipes",
"swarming_task_id:4f707113a7930e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603496247185105",
"url": "https://ci.chromium.org/b/8865609572192126336",
"utcnow_ts": "1603496247906332"
}
}
[23:37:27.916] Invocation finished in 56m16.16662868s with status FAILED