[17:25:05.089] New invocation is queued and will start shortly
[17:25:06.280] Starting the invocation (attempt 1)
[17:25:06.309] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/51d6170cbb6900e7adb6112d880a4a56d44b5f03"
[17:25:06.309] Popped gitiles commit info from properties and tags
[17:25:06.309] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:25:06.310] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:25:06.310] Buildbucket request:
{
"requestId": "8916635554556283152",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "pkg-linux-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916635554556283152",
"job": "dart/pkg-linux-debug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "3799b8691c726aebf89227a71112750be91d5b4a"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@3799b8691c726aebf89227a71112750be91d5b4a",
"title": "3799b8691c726aebf89227a71112750be91d5b4a",
"url": "https://dart.googlesource.com/sdk/+/3799b8691c726aebf89227a71112750be91d5b4a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "e68eeec2eb874da80acd38a34c6105b57c4b0e8b"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@e68eeec2eb874da80acd38a34c6105b57c4b0e8b",
"title": "e68eeec2eb874da80acd38a34c6105b57c4b0e8b",
"url": "https://dart.googlesource.com/sdk/+/e68eeec2eb874da80acd38a34c6105b57c4b0e8b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "eb7ba49329a4e19b864d32ee8c4c76a7d5fc9e87"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@eb7ba49329a4e19b864d32ee8c4c76a7d5fc9e87",
"title": "eb7ba49329a4e19b864d32ee8c4c76a7d5fc9e87",
"url": "https://dart.googlesource.com/sdk/+/eb7ba49329a4e19b864d32ee8c4c76a7d5fc9e87"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "4fe1050d29d9244a1c9281d45d3703e179b43322"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@4fe1050d29d9244a1c9281d45d3703e179b43322",
"title": "4fe1050d29d9244a1c9281d45d3703e179b43322",
"url": "https://dart.googlesource.com/sdk/+/4fe1050d29d9244a1c9281d45d3703e179b43322"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "34caa8378c39a48946dfe3db76310acca62a2b49"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@34caa8378c39a48946dfe3db76310acca62a2b49",
"title": "34caa8378c39a48946dfe3db76310acca62a2b49",
"url": "https://dart.googlesource.com/sdk/+/34caa8378c39a48946dfe3db76310acca62a2b49"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "be0cb54a9e5e801a2f54972f2d3c06550cf6df84"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@be0cb54a9e5e801a2f54972f2d3c06550cf6df84",
"title": "be0cb54a9e5e801a2f54972f2d3c06550cf6df84",
"url": "https://dart.googlesource.com/sdk/+/be0cb54a9e5e801a2f54972f2d3c06550cf6df84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "51d6170cbb6900e7adb6112d880a4a56d44b5f03"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@51d6170cbb6900e7adb6112d880a4a56d44b5f03",
"title": "51d6170cbb6900e7adb6112d880a4a56d44b5f03",
"url": "https://dart.googlesource.com/sdk/+/51d6170cbb6900e7adb6112d880a4a56d44b5f03"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "51d6170cbb6900e7adb6112d880a4a56d44b5f03",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916635554556283152"
},
{
"key": "scheduler_job_id",
"value": "dart/pkg-linux-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:25:06.828] Scheduled build:
{
"id": "8751205492541238833",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "pkg-linux-debug"
},
"number": 19964,
"createdBy": "project:dart",
"createTime": "2024-04-08T17:25:06.378224882Z",
"updateTime": "2024-04-08T17:25:06.378224882Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "51d6170cbb6900e7adb6112d880a4a56d44b5f03",
"ref": "refs/heads/main"
}
}
}
[17:25:06.828] Task URL: https://cr-buildbucket.appspot.com/build/8751205492541238833
[17:25:06.828] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:2:0) after 1m27s
[17:25:07.825] Received PubSub notification, asking Buildbucket for the build status
[17:25:07.843] Build status: SCHEDULED
[17:26:34.063] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:2:0)
[17:26:34.092] Build status: SCHEDULED
[17:26:34.093] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:4:0) after 1m34s
[17:28:08.152] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:4:0)
[17:28:08.185] Build status: SCHEDULED
[17:28:08.185] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:5:0) after 3m21s
[17:31:29.206] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:5:0)
[17:31:29.230] Build status: SCHEDULED
[17:31:29.251] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:6:0) after 2m24s
[17:33:53.380] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:6:0)
[17:33:53.483] Build status: SCHEDULED
[17:33:53.483] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:7:0) after 7m52s
[17:41:45.499] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:7:0)
[17:41:45.520] Build status: SCHEDULED
[17:41:45.520] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:8:0) after 1m3s
[17:42:48.546] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:8:0)
[17:42:48.565] Build status: SCHEDULED
[17:42:48.565] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:9:0) after 2m10s
[17:44:58.564] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:9:0)
[17:44:58.593] Build status: SCHEDULED
[17:44:58.593] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:10:0) after 5m50s
[17:50:48.835] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:10:0)
[17:50:48.887] Build status: SCHEDULED
[17:50:48.888] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:11:0) after 4m44s
[17:55:33.174] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:11:0)
[17:55:33.200] Build status: SCHEDULED
[17:55:33.200] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:12:0) after 1m12s
[17:56:45.218] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:12:0)
[17:56:45.239] Build status: SCHEDULED
[17:56:45.239] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:13:0) after 4m24s
[18:01:09.304] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:13:0)
[18:01:09.330] Build status: SCHEDULED
[18:01:09.330] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:14:0) after 5m0s
[18:06:09.312] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:14:0)
[18:06:09.344] Build status: SCHEDULED
[18:06:09.344] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:15:0) after 3m0s
[18:09:09.406] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:15:0)
[18:09:09.770] Build status: SCHEDULED
[18:09:09.770] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:16:0) after 2m35s
[18:11:44.792] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:16:0)
[18:11:44.816] Build status: SCHEDULED
[18:11:44.816] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:17:0) after 9m39s
[18:21:23.815] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:17:0)
[18:21:23.841] Build status: SCHEDULED
[18:21:23.841] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:18:0) after 8m34s
[18:29:57.968] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:18:0)
[18:29:57.990] Build status: SCHEDULED
[18:29:57.990] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:19:0) after 5m48s
[18:35:46.077] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:19:0)
[18:35:46.257] Build status: SCHEDULED
[18:35:46.257] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:20:0) after 6m24s
[18:42:10.262] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:20:0)
[18:42:10.309] Build status: SCHEDULED
[18:42:10.309] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:21:0) after 8m0s
[18:50:01.554] Received PubSub notification, asking Buildbucket for the build status
[18:50:01.573] Build status: STARTED
[18:50:10.382] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:21:0)
[18:50:10.410] Build status: STARTED
[18:50:10.411] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:23:0) after 6m57s
[18:57:07.447] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:23:0)
[18:57:07.492] Build status: STARTED
[18:57:07.492] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:24:0) after 1m49s
[18:58:57.022] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:24:0)
[18:58:57.161] Build status: STARTED
[18:58:57.161] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:25:0) after 7m8s
[19:06:05.190] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:25:0)
[19:06:05.210] Build status: STARTED
[19:06:05.210] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:26:0) after 4m17s
[19:10:22.240] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:26:0)
[19:10:22.307] Build status: STARTED
[19:10:22.307] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:27:0) after 3m11s
[19:13:33.331] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:27:0)
[19:13:33.361] Build status: STARTED
[19:13:33.361] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:28:0) after 8m42s
[19:22:15.413] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:28:0)
[19:22:15.435] Build status: STARTED
[19:22:15.435] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:29:0) after 6m19s
[19:28:34.464] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:29:0)
[19:28:34.486] Build status: STARTED
[19:28:34.486] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:30:0) after 2m50s
[19:31:24.603] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:30:0)
[19:31:24.709] Build status: STARTED
[19:31:24.709] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:31:0) after 1m11s
[19:32:35.723] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:31:0)
[19:32:35.743] Build status: STARTED
[19:32:35.743] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:32:0) after 7m36s
[19:40:11.762] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:32:0)
[19:40:11.782] Build status: STARTED
[19:40:11.782] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:33:0) after 5m8s
[19:45:19.921] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:33:0)
[19:45:19.943] Build status: STARTED
[19:45:19.944] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:34:0) after 2m48s
[19:48:08.012] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:34:0)
[19:48:08.069] Build status: STARTED
[19:48:08.069] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:35:0) after 2m1s
[19:50:09.078] Handling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:35:0)
[19:50:09.132] Build status: STARTED
[19:50:09.132] Scheduling timer "check-buildbucket-build-status" (dart/pkg-linux-debug:8916635554556283152:36:0) after 8m33s
[19:56:46.368] Received PubSub notification, asking Buildbucket for the build status
[19:56:46.429] Build:
{
"id": "8751205492541238833",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "pkg-linux-debug"
},
"number": 19964,
"createdBy": "project:dart",
"createTime": "2024-04-08T17:25:06.378224882Z",
"startTime": "2024-04-08T18:50:01.024838959Z",
"endTime": "2024-04-08T19:56:45.968562673Z",
"updateTime": "2024-04-08T19:56:45.968562673Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "51d6170cbb6900e7adb6112d880a4a56d44b5f03",
"ref": "refs/heads/main"
}
}
}
[19:56:46.429] Invocation finished in 2h31m41.357401582s with status SUCCEEDED