[17:05:17.261] New invocation is queued and will start shortly
[17:05:18.452] Starting the invocation (attempt 1)
[17:05:18.486] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/29c5b5da9ac58222cddfdce73c1e93051e3edc2d"
[17:05:18.486] Popped gitiles commit info from properties and tags
[17:05:18.486] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:05:18.486] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:05:18.486] Buildbucket request:
{
"requestId": "8952060213936484880",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "ddc-linux-chrome"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8952060213936484880",
"job": "dart/ddc-linux-chrome",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "5b3f387f4daf17e8edda36270fb8d91b33865cad"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@5b3f387f4daf17e8edda36270fb8d91b33865cad",
"title": "5b3f387f4daf17e8edda36270fb8d91b33865cad",
"url": "https://dart.googlesource.com/sdk/+/5b3f387f4daf17e8edda36270fb8d91b33865cad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "29c5b5da9ac58222cddfdce73c1e93051e3edc2d"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@29c5b5da9ac58222cddfdce73c1e93051e3edc2d",
"title": "29c5b5da9ac58222cddfdce73c1e93051e3edc2d",
"url": "https://dart.googlesource.com/sdk/+/29c5b5da9ac58222cddfdce73c1e93051e3edc2d"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "29c5b5da9ac58222cddfdce73c1e93051e3edc2d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8952060213936484880"
},
{
"key": "scheduler_job_id",
"value": "dart/ddc-linux-chrome"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:05:18.868] Scheduled build:
{
"id": "8786630151928434785",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "ddc-linux-chrome"
},
"number": 160,
"createdBy": "project:dart",
"createTime": "2023-03-14T17:05:18.553567422Z",
"updateTime": "2023-03-14T17:05:18.553567422Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "29c5b5da9ac58222cddfdce73c1e93051e3edc2d",
"ref": "refs/heads/main"
}
}
}
[17:05:18.868] Task URL: https://cr-buildbucket.appspot.com/build/8786630151928434785
[17:05:18.868] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:2:0) after 3m44s
[17:09:02.889] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:2:0)
[17:09:03.028] Build status: SCHEDULED
[17:09:03.028] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:3:0) after 1m26s
[17:10:29.050] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:3:0)
[17:10:29.110] Build status: SCHEDULED
[17:10:29.110] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:4:0) after 7m23s
[17:17:52.127] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:4:0)
[17:17:52.164] Build status: SCHEDULED
[17:17:52.164] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:5:0) after 4m19s
[17:22:11.199] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:5:0)
[17:22:11.227] Build status: SCHEDULED
[17:22:11.227] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:6:0) after 6m31s
[17:28:42.245] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:6:0)
[17:28:42.268] Build status: SCHEDULED
[17:28:42.268] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:7:0) after 7m3s
[17:35:45.356] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:7:0)
[17:35:45.378] Build status: SCHEDULED
[17:35:45.378] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:8:0) after 6m39s
[17:42:24.884] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:8:0)
[17:42:24.905] Build status: SCHEDULED
[17:42:24.905] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:9:0) after 9m43s
[17:52:07.898] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:9:0)
[17:52:07.916] Build status: SCHEDULED
[17:52:07.916] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:10:0) after 1m15s
[17:53:22.934] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:10:0)
[17:53:22.959] Build status: SCHEDULED
[17:53:22.959] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:11:0) after 2m7s
[17:55:29.986] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:11:0)
[17:55:30.014] Build status: SCHEDULED
[17:55:30.014] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:12:0) after 5m45s
[18:01:15.045] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:12:0)
[18:01:15.071] Build status: SCHEDULED
[18:01:15.071] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:13:0) after 2m44s
[18:03:59.124] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:13:0)
[18:03:59.147] Build status: SCHEDULED
[18:03:59.147] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:14:0) after 4m21s
[18:08:20.227] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:14:0)
[18:08:20.259] Build status: SCHEDULED
[18:08:20.259] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:15:0) after 3m35s
[18:11:55.273] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:15:0)
[18:11:55.304] Build status: SCHEDULED
[18:11:55.304] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:16:0) after 9m20s
[18:21:15.371] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:16:0)
[18:21:15.423] Build status: SCHEDULED
[18:21:15.423] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:17:0) after 9m25s
[18:30:40.430] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:17:0)
[18:30:40.449] Build status: SCHEDULED
[18:30:40.449] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:18:0) after 7m57s
[18:38:37.484] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:18:0)
[18:38:37.506] Build status: SCHEDULED
[18:38:37.506] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:19:0) after 3m32s
[18:42:09.526] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:19:0)
[18:42:09.562] Build status: SCHEDULED
[18:42:09.562] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:20:0) after 6m23s
[18:48:32.592] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:20:0)
[18:48:32.610] Build status: SCHEDULED
[18:48:32.610] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:21:0) after 5m48s
[18:54:20.628] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:21:0)
[18:54:20.651] Build status: SCHEDULED
[18:54:20.651] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:22:0) after 2m16s
[18:56:36.652] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:22:0)
[18:56:36.829] Build status: SCHEDULED
[18:56:36.829] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:23:0) after 9m41s
[19:06:17.951] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:23:0)
[19:06:17.995] Build status: SCHEDULED
[19:06:17.995] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:24:0) after 5m45s
[19:12:03.077] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:24:0)
[19:12:03.111] Build status: SCHEDULED
[19:12:03.111] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:25:0) after 8m45s
[19:20:48.140] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:25:0)
[19:20:48.159] Build status: SCHEDULED
[19:20:48.159] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:26:0) after 2m5s
[19:22:53.185] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:26:0)
[19:22:53.208] Build status: SCHEDULED
[19:22:53.208] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:27:0) after 1m39s
[19:24:32.335] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:27:0)
[19:24:32.356] Build status: SCHEDULED
[19:24:32.356] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:28:0) after 7m29s
[19:32:01.686] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:28:0)
[19:32:01.713] Build status: SCHEDULED
[19:32:01.713] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:29:0) after 3m28s
[19:35:29.831] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:29:0)
[19:35:29.857] Build status: SCHEDULED
[19:35:29.857] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:30:0) after 1m35s
[19:37:05.161] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:30:0)
[19:37:05.185] Build status: SCHEDULED
[19:37:05.186] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:31:0) after 3m43s
[19:40:48.204] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:31:0)
[19:40:48.228] Build status: SCHEDULED
[19:40:48.228] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:32:0) after 4m5s
[19:44:54.007] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:32:0)
[19:44:54.032] Build status: SCHEDULED
[19:44:54.032] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:33:0) after 5m45s
[19:50:39.068] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:33:0)
[19:50:39.096] Build status: SCHEDULED
[19:50:39.096] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:34:0) after 1m35s
[19:52:14.328] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:34:0)
[19:52:14.350] Build status: SCHEDULED
[19:52:14.350] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:35:0) after 3m0s
[19:55:14.412] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:35:0)
[19:55:14.437] Build status: SCHEDULED
[19:55:14.437] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:36:0) after 1m18s
[19:56:32.452] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:36:0)
[19:56:32.505] Build status: SCHEDULED
[19:56:32.505] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:37:0) after 9m35s
[20:06:07.536] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:37:0)
[20:06:07.560] Build status: SCHEDULED
[20:06:07.560] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:38:0) after 7m59s
[20:14:06.622] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:38:0)
[20:14:06.808] Build status: SCHEDULED
[20:14:06.808] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:39:0) after 4m33s
[20:18:40.066] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:39:0)
[20:18:40.105] Build status: SCHEDULED
[20:18:40.105] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:40:0) after 4m20s
[20:23:00.124] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:40:0)
[20:23:00.151] Build status: SCHEDULED
[20:23:00.151] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:41:0) after 5m25s
[20:28:25.128] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:41:0)
[20:28:25.234] Build status: SCHEDULED
[20:28:25.235] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:42:0) after 7m59s
[20:36:24.261] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:42:0)
[20:36:24.281] Build status: SCHEDULED
[20:36:24.281] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:43:0) after 9m21s
[20:45:45.305] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:43:0)
[20:45:45.341] Build status: SCHEDULED
[20:45:45.341] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:44:0) after 7m1s
[20:47:42.218] Received PubSub notification, asking Buildbucket for the build status
[20:47:42.242] Build status: STARTED
[20:52:46.368] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:44:0)
[20:52:46.388] Build status: STARTED
[20:52:46.388] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:46:0) after 4m12s
[20:56:58.463] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:46:0)
[20:56:58.486] Build status: STARTED
[20:56:58.486] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:47:0) after 4m16s
[21:01:14.651] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:47:0)
[21:01:14.672] Build status: STARTED
[21:01:14.672] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:48:0) after 8m46s
[21:10:00.688] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:48:0)
[21:10:00.719] Build status: STARTED
[21:10:00.719] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:49:0) after 9m52s
[21:19:52.932] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:49:0)
[21:19:52.953] Build status: STARTED
[21:19:52.953] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:50:0) after 2m56s
[21:22:49.186] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:50:0)
[21:22:49.211] Build status: STARTED
[21:22:49.211] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:51:0) after 2m33s
[21:25:22.620] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:51:0)
[21:25:22.639] Build status: STARTED
[21:25:22.639] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:52:0) after 8m1s
[21:33:23.673] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:52:0)
[21:33:23.712] Build status: STARTED
[21:33:23.712] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:53:0) after 7m11s
[21:40:35.471] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:53:0)
[21:40:35.497] Build status: STARTED
[21:40:35.497] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:54:0) after 1m19s
[21:41:54.517] Handling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:54:0)
[21:41:54.540] Build status: STARTED
[21:41:54.540] Scheduling timer "check-buildbucket-build-status" (dart/ddc-linux-chrome:8952060213936484880:55:0) after 9m46s
[21:49:27.688] Received PubSub notification, asking Buildbucket for the build status
[21:49:27.720] Build:
{
"id": "8786630151928434785",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "ddc-linux-chrome"
},
"number": 160,
"createdBy": "project:dart",
"createTime": "2023-03-14T17:05:18.553567422Z",
"startTime": "2023-03-14T20:47:41.514752Z",
"endTime": "2023-03-14T21:49:26.802792151Z",
"updateTime": "2023-03-14T21:49:26.802792151Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "29c5b5da9ac58222cddfdce73c1e93051e3edc2d",
"ref": "refs/heads/main"
}
}
}
[21:49:27.720] Invocation finished in 4h44m10.466552401s with status SUCCEEDED