[01:02:30.248] New invocation is queued and will start shortly
[01:02:31.554] Starting the invocation (attempt 1)
[01:02:31.664] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:02:31.664] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:02:31.664] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9062467892149803920",
"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\":\"da561313c6db54892db984e21bc6184a8ff21938\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@da561313c6db54892db984e21bc6184a8ff21938\",\"title\":\"da561313c6db54892db984e21bc6184a8ff21938\",\"url\":\"https://dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk\",\"revision\":\"da561313c6db54892db984e21bc6184a8ff21938\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:analyzer-win-release",
"scheduler_invocation_id:9062467892149803920",
"scheduler_job_id:dart/analyzer-win-release",
"user_agent:luci-scheduler",
"buildset:commit/git/da561313c6db54892db984e21bc6184a8ff21938",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938",
"gitiles_ref:refs/heads/master"
]
}
[01:02:32.962] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1573520551951874",
"id": "8897037829675162960",
"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\": \"da561313c6db54892db984e21bc6184a8ff21938\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@da561313c6db54892db984e21bc6184a8ff21938\", \"title\": \"da561313c6db54892db984e21bc6184a8ff21938\", \"url\": \"https://dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"da561313c6db54892db984e21bc6184a8ff21938\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1573520552632942",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-win-release/7786",
"builder:analyzer-win-release",
"buildset:commit/git/da561313c6db54892db984e21bc6184a8ff21938",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062467892149803920",
"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/8897037829675162960/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1573520552633113",
"url": "https://ci.chromium.org/b/8897037829675162960",
"utcnow_ts": "1573520552947434"
}
}
[01:02:32.962] Task URL: https://ci.chromium.org/b/8897037829675162960
[01:02:32.962] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:2:0) after 1m0s
[01:03:33.119] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:2:0)
[01:03:33.119] Timer tick, asking Buildbucket for the build status
[01:03:35.215] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:03:35.215] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:3:0) after 1m0s
[01:04:35.422] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:3:0)
[01:04:35.422] Timer tick, asking Buildbucket for the build status
[01:04:36.549] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:04:36.549] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:4:0) after 1m0s
[01:05:36.604] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:4:0)
[01:05:36.604] Timer tick, asking Buildbucket for the build status
[01:05:36.880] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:05:36.880] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:5:0) after 1m0s
[01:06:36.914] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:5:0)
[01:06:36.914] Timer tick, asking Buildbucket for the build status
[01:06:38.874] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:06:38.874] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:6:0) after 1m0s
[01:07:39.159] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:6:0)
[01:07:39.159] Timer tick, asking Buildbucket for the build status
[01:07:41.307] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:07:41.307] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:7:0) after 1m0s
[01:08:42.054] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:7:0)
[01:08:42.054] Timer tick, asking Buildbucket for the build status
[01:08:42.546] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:08:42.546] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:8:0) after 1m0s
[01:09:42.694] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:8:0)
[01:09:42.694] Timer tick, asking Buildbucket for the build status
[01:09:42.959] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:09:42.959] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:9:0) after 1m0s
[01:10:43.469] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:9:0)
[01:10:43.469] Timer tick, asking Buildbucket for the build status
[01:10:43.781] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:10:43.781] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:10:0) after 1m0s
[01:11:43.861] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:10:0)
[01:11:43.861] Timer tick, asking Buildbucket for the build status
[01:11:44.351] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:11:44.352] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:11:0) after 1m0s
[01:12:44.517] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:11:0)
[01:12:44.517] Timer tick, asking Buildbucket for the build status
[01:12:46.584] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:12:46.585] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:12:0) after 1m0s
[01:13:46.607] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:12:0)
[01:13:46.607] Timer tick, asking Buildbucket for the build status
[01:13:48.229] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:13:48.229] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:13:0) after 1m0s
[01:14:48.292] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:13:0)
[01:14:48.292] Timer tick, asking Buildbucket for the build status
[01:14:48.486] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:14:48.487] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:14:0) after 1m0s
[01:15:48.493] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:14:0)
[01:15:48.493] Timer tick, asking Buildbucket for the build status
[01:15:48.845] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:15:48.845] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:15:0) after 1m0s
[01:16:48.903] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:15:0)
[01:16:48.904] Timer tick, asking Buildbucket for the build status
[01:16:50.167] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:16:50.167] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:16:0) after 1m0s
[01:17:50.506] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:16:0)
[01:17:50.506] Timer tick, asking Buildbucket for the build status
[01:17:50.702] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:17:50.702] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:17:0) after 1m0s
[01:18:50.969] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:17:0)
[01:18:50.969] Timer tick, asking Buildbucket for the build status
[01:18:54.325] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:18:54.325] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:18:0) after 1m0s
[01:19:54.351] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:18:0)
[01:19:54.351] Timer tick, asking Buildbucket for the build status
[01:19:55.961] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:19:55.961] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:19:0) after 1m0s
[01:20:56.088] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:19:0)
[01:20:56.088] Timer tick, asking Buildbucket for the build status
[01:20:57.961] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:20:57.961] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:20:0) after 1m0s
[01:21:58.170] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:20:0)
[01:21:58.170] Timer tick, asking Buildbucket for the build status
[01:21:58.390] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:21:58.390] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:21:0) after 1m0s
[01:22:58.496] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:21:0)
[01:22:58.496] Timer tick, asking Buildbucket for the build status
[01:22:58.727] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:22:58.727] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:22:0) after 1m0s
[01:23:59.018] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:22:0)
[01:23:59.018] Timer tick, asking Buildbucket for the build status
[01:23:59.131] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:23:59.132] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:23:0) after 1m0s
[01:24:59.323] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:23:0)
[01:24:59.323] Timer tick, asking Buildbucket for the build status
[01:25:00.416] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:25:00.416] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:24:0) after 1m0s
[01:26:00.639] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:24:0)
[01:26:00.639] Timer tick, asking Buildbucket for the build status
[01:26:02.133] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:26:02.133] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:25:0) after 1m0s
[01:27:02.509] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:25:0)
[01:27:02.509] Timer tick, asking Buildbucket for the build status
[01:27:03.011] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:27:03.011] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:26:0) after 1m0s
[01:28:03.116] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:26:0)
[01:28:03.116] Timer tick, asking Buildbucket for the build status
[01:28:06.444] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:28:06.445] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:27:0) after 1m0s
[01:29:06.488] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:27:0)
[01:29:06.488] Timer tick, asking Buildbucket for the build status
[01:29:07.408] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:29:07.408] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:28:0) after 1m0s
[01:30:07.591] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:28:0)
[01:30:07.591] Timer tick, asking Buildbucket for the build status
[01:30:07.913] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:30:07.913] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:29:0) after 1m0s
[01:31:08.125] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:29:0)
[01:31:08.125] Timer tick, asking Buildbucket for the build status
[01:31:09.282] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:31:09.282] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:30:0) after 1m0s
[01:32:09.361] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:30:0)
[01:32:09.361] Timer tick, asking Buildbucket for the build status
[01:32:11.970] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:32:11.970] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:31:0) after 1m0s
[01:33:12.442] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:31:0)
[01:33:12.442] Timer tick, asking Buildbucket for the build status
[01:33:12.896] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:33:12.896] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:32:0) after 1m0s
[01:34:13.417] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:32:0)
[01:34:13.417] Timer tick, asking Buildbucket for the build status
[01:34:15.441] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:34:15.441] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:33:0) after 1m0s
[01:35:15.501] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:33:0)
[01:35:15.501] Timer tick, asking Buildbucket for the build status
[01:35:20.984] Build 8897037829675162960: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:35:20.984] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:34:0) after 1m0s
[01:35:34.066] Received PubSub notification, asking Buildbucket for the build status
[01:35:36.064] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:21.081] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:34:0)
[01:36:21.081] Timer tick, asking Buildbucket for the build status
[01:36:24.681] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:24.681] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:36:0) after 1m0s
[01:37:24.820] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:36:0)
[01:37:24.820] Timer tick, asking Buildbucket for the build status
[01:37:26.082] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:26.082] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:37:0) after 1m0s
[01:38:26.185] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:37:0)
[01:38:26.185] Timer tick, asking Buildbucket for the build status
[01:38:26.957] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:26.957] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:38:0) after 1m0s
[01:39:27.104] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:38:0)
[01:39:27.104] Timer tick, asking Buildbucket for the build status
[01:39:27.419] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:27.419] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:39:0) after 1m0s
[01:40:27.534] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:39:0)
[01:40:27.534] Timer tick, asking Buildbucket for the build status
[01:40:27.815] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:27.815] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:40:0) after 1m0s
[01:41:27.837] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:40:0)
[01:41:27.837] Timer tick, asking Buildbucket for the build status
[01:41:29.279] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:29.279] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:41:0) after 1m0s
[01:42:29.313] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:41:0)
[01:42:29.313] Timer tick, asking Buildbucket for the build status
[01:42:29.510] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:29.510] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:42:0) after 1m0s
[01:43:29.555] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:42:0)
[01:43:29.555] Timer tick, asking Buildbucket for the build status
[01:43:29.831] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:29.831] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:43:0) after 1m0s
[01:44:29.891] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:43:0)
[01:44:29.891] Timer tick, asking Buildbucket for the build status
[01:44:30.825] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:30.825] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:44:0) after 1m0s
[01:45:30.848] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:44:0)
[01:45:30.848] Timer tick, asking Buildbucket for the build status
[01:45:31.125] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:31.125] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:45:0) after 1m0s
[01:46:31.211] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:45:0)
[01:46:31.211] Timer tick, asking Buildbucket for the build status
[01:46:31.768] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:31.768] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:46:0) after 1m0s
[01:47:31.821] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:46:0)
[01:47:31.821] Timer tick, asking Buildbucket for the build status
[01:47:32.487] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:32.487] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:47:0) after 1m0s
[01:48:32.544] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:47:0)
[01:48:32.544] Timer tick, asking Buildbucket for the build status
[01:48:34.183] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:34.183] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:48:0) after 1m0s
[01:49:34.308] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:48:0)
[01:49:34.308] Timer tick, asking Buildbucket for the build status
[01:49:34.557] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:34.557] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:49:0) after 1m0s
[01:50:34.562] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:49:0)
[01:50:34.562] Timer tick, asking Buildbucket for the build status
[01:50:35.418] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:35.418] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:50:0) after 1m0s
[01:51:35.612] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:50:0)
[01:51:35.612] Timer tick, asking Buildbucket for the build status
[01:51:35.932] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:35.932] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:51:0) after 1m0s
[01:52:36.015] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:51:0)
[01:52:36.015] Timer tick, asking Buildbucket for the build status
[01:52:36.457] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:36.457] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:52:0) after 1m0s
[01:53:36.575] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:52:0)
[01:53:36.575] Timer tick, asking Buildbucket for the build status
[01:53:36.845] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:36.845] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:53:0) after 1m0s
[01:54:37.001] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:53:0)
[01:54:37.001] Timer tick, asking Buildbucket for the build status
[01:54:37.410] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:37.410] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:54:0) after 1m0s
[01:55:37.467] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:54:0)
[01:55:37.467] Timer tick, asking Buildbucket for the build status
[01:55:37.725] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:37.725] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:55:0) after 1m0s
[01:56:37.754] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:55:0)
[01:56:37.754] Timer tick, asking Buildbucket for the build status
[01:56:38.956] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:38.956] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:56:0) after 1m0s
[01:57:38.992] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:56:0)
[01:57:38.992] Timer tick, asking Buildbucket for the build status
[01:57:39.603] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:39.603] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:57:0) after 1m0s
[01:58:39.673] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:57:0)
[01:58:39.673] Timer tick, asking Buildbucket for the build status
[01:58:39.972] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:39.972] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:58:0) after 1m0s
[01:59:39.955] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:58:0)
[01:59:39.955] Timer tick, asking Buildbucket for the build status
[01:59:40.183] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:40.183] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:59:0) after 1m0s
[02:00:40.244] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:59:0)
[02:00:40.244] Timer tick, asking Buildbucket for the build status
[02:00:40.587] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:40.587] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:60:0) after 1m0s
[02:01:40.649] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:60:0)
[02:01:40.649] Timer tick, asking Buildbucket for the build status
[02:01:40.779] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:40.779] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:61:0) after 1m0s
[02:02:40.845] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:61:0)
[02:02:40.845] Timer tick, asking Buildbucket for the build status
[02:02:41.661] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:41.661] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:62:0) after 1m0s
[02:03:41.659] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:62:0)
[02:03:41.659] Timer tick, asking Buildbucket for the build status
[02:03:42.312] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:42.312] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:63:0) after 1m0s
[02:04:42.392] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:63:0)
[02:04:42.392] Timer tick, asking Buildbucket for the build status
[02:04:42.559] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:42.559] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:64:0) after 1m0s
[02:05:42.689] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:64:0)
[02:05:42.689] Timer tick, asking Buildbucket for the build status
[02:05:43.065] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:43.065] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:65:0) after 1m0s
[02:06:43.138] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:65:0)
[02:06:43.138] Timer tick, asking Buildbucket for the build status
[02:06:43.467] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:43.467] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:66:0) after 1m0s
[02:07:43.484] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:66:0)
[02:07:43.484] Timer tick, asking Buildbucket for the build status
[02:07:43.756] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:43.756] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:67:0) after 1m0s
[02:08:43.757] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:67:0)
[02:08:43.757] Timer tick, asking Buildbucket for the build status
[02:08:45.699] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:45.699] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:68:0) after 1m0s
[02:09:45.723] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:68:0)
[02:09:45.723] Timer tick, asking Buildbucket for the build status
[02:09:46.185] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:46.185] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:69:0) after 1m0s
[02:10:46.318] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:69:0)
[02:10:46.318] Timer tick, asking Buildbucket for the build status
[02:10:46.704] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:46.704] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:70:0) after 1m0s
[02:11:46.787] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:70:0)
[02:11:46.787] Timer tick, asking Buildbucket for the build status
[02:11:47.188] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:47.188] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:71:0) after 1m0s
[02:12:47.227] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:71:0)
[02:12:47.227] Timer tick, asking Buildbucket for the build status
[02:12:48.016] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:48.016] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:72:0) after 1m0s
[02:13:48.038] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:72:0)
[02:13:48.038] Timer tick, asking Buildbucket for the build status
[02:13:49.851] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:49.851] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:73:0) after 1m0s
[02:14:49.842] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:73:0)
[02:14:49.842] Timer tick, asking Buildbucket for the build status
[02:14:50.011] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:50.011] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:74:0) after 1m0s
[02:15:50.127] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:74:0)
[02:15:50.127] Timer tick, asking Buildbucket for the build status
[02:15:50.293] Build 8897037829675162960: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:50.293] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9062467892149803920:75:0) after 1m0s
[02:16:46.886] Received PubSub notification, asking Buildbucket for the build status
[02:16:47.079] Build 8897037829675162960: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:16:47.079] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"completed_ts": "1573525005928653",
"created_by": "project:dart",
"created_ts": "1573520551951874",
"id": "8897037829675162960",
"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\": \"da561313c6db54892db984e21bc6184a8ff21938\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@da561313c6db54892db984e21bc6184a8ff21938\", \"title\": \"da561313c6db54892db984e21bc6184a8ff21938\", \"url\": \"https://dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"da561313c6db54892db984e21bc6184a8ff21938\"}}",
"project": "dart",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"da561313c6db54892db984e21bc6184a8ff21938\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@da561313c6db54892db984e21bc6184a8ff21938\", \"title\": \"da561313c6db54892db984e21bc6184a8ff21938\", \"url\": \"https://dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938\"}]}, \"bot_id\": \"luci-dart-try-win10-12-k7ai\", \"branch\": \"refs/heads/master\", \"buildername\": \"analyzer-win-release\", \"buildnumber\": 7786, \"clobber\": \"true\", \"got_revision\": \"da561313c6db54892db984e21bc6184a8ff21938\", \"new_workflow_enabled\": true, \"path_config\": \"generic\", \"recipe\": \"dart/neo\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"da561313c6db54892db984e21bc6184a8ff21938\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_253c87b3a44493e86c84588bb71d4c5302204a0da30649c89ab584c62b6076aa_v2\", \"builder_52304b3d18db17d8e1578a10f4aad442eeba949dbab2c7b3531ef01be5acfde1_v2\", \"builder_8095c369da6d1c4fc4bdea3bed6bfc7e7611dcdbf656f7e9397906f16f7edd82_v2\", \"builder_a970b7bdf2d56b0bbe03c0844d521d30f6ec8171c16ba60dcf5a9e6bb9c1cdfe_v2\", \"builder_d02729cd0bcb9ed4dcfd264ab55400bbd17edd2afc22154c271202611b50f841_v2\", \"builder_d6f396784d1bacfeb26d12c7d2f46eacb6fe10d26ab756565a33004b6afc6592_v2\", \"builder_ef3e73fc15b32b92058bb8d202e07de466ff011148432befe1b168cce52937e0_v2\", \"builder_efaba49ac01142e4cf8a2fc84fa44c69748795400c7bcb6fa54e885ccb531c8d_v2\", \"builder_f1852b00fe0ca09c8d99c9b59d72a6be8b1aa57c5b29ffd31bb3640d6f0f463d_v2\", \"builder_fdfe16f80236f8a8ea5ac7c31eb3a314de1180da1c89a5b2f17bde81d3255c83_v2\", \"git\", \"goma_v2\", \"swarming_module_cache_vpython\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-dart-try-win10-12-k7ai\"], \"image\": [\"chrome-win10-1703-19102400-d35fedf5a0d\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"dart.tests\", \"luci.dart.try\"], \"python\": [\"2.7.13\"], \"server_version\": [\"4621-f8a5b1c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"]}}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1573522532229366",
"status": "COMPLETED",
"status_changed_ts": "1573525006402052",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-win-release/7786",
"builder:analyzer-win-release",
"buildset:commit/git/da561313c6db54892db984e21bc6184a8ff21938",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/da561313c6db54892db984e21bc6184a8ff21938",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062467892149803920",
"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/8897037829675162960/+/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:4875f40e111ebf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1573525006453754",
"url": "https://ci.chromium.org/b/8897037829675162960",
"utcnow_ts": "1573525007064694"
}
}
[02:16:47.079] Invocation finished in 1h14m16.870263021s with status SUCCEEDED