[15:19:11.555] New invocation is queued and will start shortly
[15:19:12.702] Starting the invocation (attempt 1)
[15:19:12.740] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:19:12.741] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:19:12.741] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9017477898612052080",
"parameters_json": "{\"builder_name\":\"pkg-win-release\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://dart.googlesource.com/sdk\",\"revision\":\"339263266745c37961732942daf0759a880290ca\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@339263266745c37961732942daf0759a880290ca\",\"title\":\"339263266745c37961732942daf0759a880290ca\",\"url\":\"https://dart.googlesource.com/sdk/+/339263266745c37961732942daf0759a880290ca\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://dart.googlesource.com/sdk\",\"revision\":\"d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\",\"title\":\"d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\",\"url\":\"https://dart.googlesource.com/sdk/+/d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk\",\"revision\":\"d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:pkg-win-release",
"scheduler_invocation_id:9017477898612052080",
"scheduler_job_id:dart/pkg-win-release",
"user_agent:luci-scheduler",
"buildset:commit/git/d82ecdfe01e1fd4cd8484ac920a1dea5761dc273",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/d82ecdfe01e1fd4cd8484ac920a1dea5761dc273",
"gitiles_ref:refs/heads/master"
]
}
[15:19:13.493] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1616426352815720",
"id": "8852047836628717536",
"parameters_json": "{\"builder_name\": \"pkg-win-release\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"339263266745c37961732942daf0759a880290ca\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@339263266745c37961732942daf0759a880290ca\", \"title\": \"339263266745c37961732942daf0759a880290ca\", \"url\": \"https://dart.googlesource.com/sdk/+/339263266745c37961732942daf0759a880290ca\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\", \"title\": \"d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\", \"url\": \"https://dart.googlesource.com/sdk/+/d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"d82ecdfe01e1fd4cd8484ac920a1dea5761dc273\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616426353147135",
"tags": [
"build_address:luci.dart.ci.sandbox/pkg-win-release/15522",
"builder:pkg-win-release",
"buildset:commit/git/d82ecdfe01e1fd4cd8484ac920a1dea5761dc273",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/d82ecdfe01e1fd4cd8484ac920a1dea5761dc273",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017477898612052080",
"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/8852047836628717536/+/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": "1616426353147236",
"url": "https://ci.chromium.org/b/8852047836628717536",
"utcnow_ts": "1616426353475414"
}
}
[15:19:13.493] Task URL: https://ci.chromium.org/b/8852047836628717536
[15:19:13.493] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:2:0) after 1m0s
[15:20:13.599] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:2:0)
[15:20:13.647] Build status: SCHEDULED
[15:20:13.647] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:3:0) after 2m30s
[15:22:43.663] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:3:0)
[15:22:43.663] Timer tick, asking Buildbucket for the build status
[15:22:43.778] Build 8852047836628717536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:22:43.778] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:4:0) after 1m0s
[15:23:43.799] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:4:0)
[15:23:43.799] Timer tick, asking Buildbucket for the build status
[15:23:43.893] Build 8852047836628717536: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:23:43.893] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:5:0) after 1m0s
[15:24:43.919] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:5:0)
[15:24:43.946] Build status: SCHEDULED
[15:24:43.946] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:6:0) after 5m11s
[15:27:25.757] Received PubSub notification, asking Buildbucket for the build status
[15:27:25.842] Build 8852047836628717536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:54.994] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:6:0)
[15:29:55.024] Build status: STARTED
[15:29:55.024] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:8:0) after 4m12s
[15:34:07.146] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:8:0)
[15:34:07.146] Timer tick, asking Buildbucket for the build status
[15:34:07.260] Build 8852047836628717536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:07.260] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:9:0) after 1m0s
[15:35:07.278] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:9:0)
[15:35:07.303] Build status: STARTED
[15:35:07.303] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:10:0) after 3m41s
[15:38:48.373] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:10:0)
[15:38:48.404] Build status: STARTED
[15:38:48.404] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:11:0) after 8m0s
[15:46:48.421] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:11:0)
[15:46:48.462] Build status: STARTED
[15:46:48.462] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:12:0) after 2m36s
[15:49:24.466] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:12:0)
[15:49:24.466] Timer tick, asking Buildbucket for the build status
[15:49:24.573] Build 8852047836628717536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:24.573] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:13:0) after 1m0s
[15:50:24.644] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:13:0)
[15:50:24.644] Timer tick, asking Buildbucket for the build status
[15:50:24.853] Build 8852047836628717536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:24.853] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:14:0) after 1m0s
[15:51:24.933] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:14:0)
[15:51:24.933] Timer tick, asking Buildbucket for the build status
[15:51:25.034] Build 8852047836628717536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:25.034] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:15:0) after 1m0s
[15:52:25.102] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:15:0)
[15:52:25.135] Build status: STARTED
[15:52:25.135] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:16:0) after 5m0s
[15:57:25.153] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:16:0)
[15:57:25.180] Build status: STARTED
[15:57:25.180] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:17:0) after 1m30s
[15:58:55.195] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:17:0)
[15:58:55.195] Timer tick, asking Buildbucket for the build status
[15:58:55.630] Build 8852047836628717536: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:55.630] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:18:0) after 1m0s
[15:59:55.632] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:18:0)
[15:59:55.659] Build status: STARTED
[15:59:55.659] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:19:0) after 1m2s
[16:00:57.694] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:19:0)
[16:00:57.722] Build status: STARTED
[16:00:57.722] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:20:0) after 2m6s
[16:03:03.837] Handling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:20:0)
[16:03:03.881] Build status: STARTED
[16:03:03.881] Scheduling timer "check-buildbucket-build-status" (dart/pkg-win-release:9017477898612052080:21:0) after 7m15s
[16:08:21.403] Received PubSub notification, asking Buildbucket for the build status
[16:08:21.433] Build:
{
"id": "8852047836628717536",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "pkg-win-release"
},
"number": 15522,
"createdBy": "project:dart",
"createTime": "2021-03-22T15:19:12.815720Z",
"startTime": "2021-03-22T15:27:24.514788Z",
"endTime": "2021-03-22T16:08:20.518471Z",
"updateTime": "2021-03-22T16:08:21.087589Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "d82ecdfe01e1fd4cd8484ac920a1dea5761dc273",
"ref": "refs/heads/master"
}
}
}
[16:08:21.433] Invocation finished in 49m9.893141961s with status SUCCEEDED