[22:14:10.949] New invocation is queued and will start shortly
[22:14:12.129] Starting the invocation (attempt 1)
[22:14:12.164] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:14:12.164] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:14:12.164] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9017904774538019376",
"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\":\"3c55d8bb3ebaeddab2f1d577c4371ed149075242\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@3c55d8bb3ebaeddab2f1d577c4371ed149075242\",\"title\":\"3c55d8bb3ebaeddab2f1d577c4371ed149075242\",\"url\":\"https://dart.googlesource.com/sdk/+/3c55d8bb3ebaeddab2f1d577c4371ed149075242\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk\",\"revision\":\"3c55d8bb3ebaeddab2f1d577c4371ed149075242\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:analyzer-win-release",
"scheduler_invocation_id:9017904774538019376",
"scheduler_job_id:dart/analyzer-win-release",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/3c55d8bb3ebaeddab2f1d577c4371ed149075242",
"gitiles_ref:refs/heads/master"
]
}
[22:14:12.859] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1616019252251002",
"id": "8852474712509838992",
"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\": \"3c55d8bb3ebaeddab2f1d577c4371ed149075242\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@3c55d8bb3ebaeddab2f1d577c4371ed149075242\", \"title\": \"3c55d8bb3ebaeddab2f1d577c4371ed149075242\", \"url\": \"https://dart.googlesource.com/sdk/+/3c55d8bb3ebaeddab2f1d577c4371ed149075242\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"3c55d8bb3ebaeddab2f1d577c4371ed149075242\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616019252735726",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-win-release/14258",
"builder:analyzer-win-release",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/3c55d8bb3ebaeddab2f1d577c4371ed149075242",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017904774538019376",
"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/8852474712509838992/+/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": "1616019252735832",
"url": "https://ci.chromium.org/b/8852474712509838992",
"utcnow_ts": "1616019252851335"
}
}
[22:14:12.859] Task URL: https://ci.chromium.org/b/8852474712509838992
[22:14:12.859] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:2:0) after 1m0s
[22:14:44.995] Received PubSub notification, asking Buildbucket for the build status
[22:14:45.113] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:12.906] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:2:0)
[22:15:12.906] Timer tick, asking Buildbucket for the build status
[22:15:12.969] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:12.969] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:4:0) after 1m0s
[22:16:13.207] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:4:0)
[22:16:13.207] Timer tick, asking Buildbucket for the build status
[22:16:13.291] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:13.291] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:5:0) after 1m0s
[22:17:13.292] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:5:0)
[22:17:13.292] Timer tick, asking Buildbucket for the build status
[22:17:13.367] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:13.367] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:6:0) after 1m0s
[22:18:13.454] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:6:0)
[22:18:13.454] Timer tick, asking Buildbucket for the build status
[22:18:13.604] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:13.604] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:7:0) after 1m0s
[22:19:13.627] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:7:0)
[22:19:13.627] Timer tick, asking Buildbucket for the build status
[22:19:13.708] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:13.708] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:8:0) after 1m0s
[22:20:13.710] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:8:0)
[22:20:13.744] Build status: STARTED
[22:20:13.744] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:9:0) after 3m37s
[22:23:50.762] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:9:0)
[22:23:50.763] Timer tick, asking Buildbucket for the build status
[22:23:50.886] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:50.886] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:10:0) after 1m0s
[22:24:50.910] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:10:0)
[22:24:50.944] Build status: STARTED
[22:24:50.944] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:11:0) after 6m13s
[22:31:04.011] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:11:0)
[22:31:04.104] Build status: STARTED
[22:31:04.104] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:12:0) after 2m20s
[22:33:24.145] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:12:0)
[22:33:24.178] Build status: STARTED
[22:33:24.178] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:13:0) after 1m15s
[22:34:39.369] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:13:0)
[22:34:39.369] Timer tick, asking Buildbucket for the build status
[22:34:39.479] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:39.479] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:14:0) after 1m0s
[22:35:39.597] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:14:0)
[22:35:39.597] Timer tick, asking Buildbucket for the build status
[22:35:39.665] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:39.665] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:15:0) after 1m0s
[22:36:39.716] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:15:0)
[22:36:39.716] Timer tick, asking Buildbucket for the build status
[22:36:40.022] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:40.022] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:16:0) after 1m0s
[22:37:40.022] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:16:0)
[22:37:40.022] Timer tick, asking Buildbucket for the build status
[22:37:40.099] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:40.099] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:17:0) after 1m0s
[22:38:40.150] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:17:0)
[22:38:40.150] Timer tick, asking Buildbucket for the build status
[22:38:40.502] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:40.502] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:18:0) after 1m0s
[22:39:40.553] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:18:0)
[22:39:40.553] Timer tick, asking Buildbucket for the build status
[22:39:40.664] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:40.664] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:19:0) after 1m0s
[22:40:40.711] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:19:0)
[22:40:40.741] Build status: STARTED
[22:40:40.741] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:20:0) after 1m19s
[22:41:59.760] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:20:0)
[22:41:59.760] Timer tick, asking Buildbucket for the build status
[22:41:59.849] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:59.849] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:21:0) after 1m0s
[22:42:59.899] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:21:0)
[22:42:59.899] Timer tick, asking Buildbucket for the build status
[22:43:00.267] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:00.267] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:22:0) after 1m0s
[22:44:00.283] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:22:0)
[22:44:00.283] Timer tick, asking Buildbucket for the build status
[22:44:00.754] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:00.754] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:23:0) after 1m0s
[22:45:00.895] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:23:0)
[22:45:00.895] Timer tick, asking Buildbucket for the build status
[22:45:01.201] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:01.201] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:24:0) after 1m0s
[22:46:01.488] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:24:0)
[22:46:01.488] Timer tick, asking Buildbucket for the build status
[22:46:01.595] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:01.595] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:25:0) after 1m0s
[22:47:01.618] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:25:0)
[22:47:01.618] Timer tick, asking Buildbucket for the build status
[22:47:01.694] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:01.694] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:26:0) after 1m0s
[22:48:01.716] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:26:0)
[22:48:01.716] Timer tick, asking Buildbucket for the build status
[22:48:01.797] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:01.797] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:27:0) after 1m0s
[22:49:01.820] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:27:0)
[22:49:01.820] Timer tick, asking Buildbucket for the build status
[22:49:01.996] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:01.996] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:28:0) after 1m0s
[22:50:02.015] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:28:0)
[22:50:02.015] Timer tick, asking Buildbucket for the build status
[22:50:02.133] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:02.133] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:29:0) after 1m0s
[22:51:02.151] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:29:0)
[22:51:02.151] Timer tick, asking Buildbucket for the build status
[22:51:02.357] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:02.357] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:30:0) after 1m0s
[22:52:02.514] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:30:0)
[22:52:02.514] Timer tick, asking Buildbucket for the build status
[22:52:02.583] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:02.583] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:31:0) after 1m0s
[22:53:02.587] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:31:0)
[22:53:02.587] Timer tick, asking Buildbucket for the build status
[22:53:02.667] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:02.667] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:32:0) after 1m0s
[22:54:02.754] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:32:0)
[22:54:02.754] Timer tick, asking Buildbucket for the build status
[22:54:03.003] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:03.003] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:33:0) after 1m0s
[22:55:03.059] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:33:0)
[22:55:03.059] Timer tick, asking Buildbucket for the build status
[22:55:03.175] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:03.175] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:34:0) after 1m0s
[22:56:03.265] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:34:0)
[22:56:03.298] Build status: STARTED
[22:56:03.298] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:35:0) after 6m28s
[23:02:31.317] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:35:0)
[23:02:31.317] Timer tick, asking Buildbucket for the build status
[23:02:31.423] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:31.423] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:36:0) after 1m0s
[23:03:31.444] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:36:0)
[23:03:31.444] Timer tick, asking Buildbucket for the build status
[23:03:31.522] Build 8852474712509838992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:31.522] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:37:0) after 1m0s
[23:04:31.578] Handling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:37:0)
[23:04:31.621] Build status: STARTED
[23:04:31.621] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-win-release:9017904774538019376:38:0) after 2m38s
[23:04:46.766] Received PubSub notification, asking Buildbucket for the build status
[23:04:46.802] Build:
{
"id": "8852474712509838992",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "analyzer-win-release"
},
"number": 14258,
"createdBy": "project:dart",
"createTime": "2021-03-17T22:14:12.251002Z",
"startTime": "2021-03-17T22:14:44.217723Z",
"endTime": "2021-03-17T23:04:45.902103Z",
"updateTime": "2021-03-17T23:04:46.197875Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "3c55d8bb3ebaeddab2f1d577c4371ed149075242",
"ref": "refs/heads/master"
}
}
}
[23:04:46.802] Invocation finished in 50m35.869855746s with status SUCCEEDED