[19:58:56.842] New invocation is queued and will start shortly
[19:58:58.576] Starting the invocation (attempt 1)
[19:58:58.610] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/8a39d781ce517846d298bc80ea4fdf2863b40b1f"
[19:58:58.610] Popped gitiles commit info from properties and tags
[19:58:58.610] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:58:58.611] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:58:58.611] Buildbucket request:
{
"requestId": "8955129585080485312",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-appjit-linux-debug-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8955129585080485312",
"job": "dart/vm-appjit-linux-debug-x64",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "6271d26baf498ab2b053c52527bc65f7f43e802b"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@6271d26baf498ab2b053c52527bc65f7f43e802b",
"title": "6271d26baf498ab2b053c52527bc65f7f43e802b",
"url": "https://dart.googlesource.com/sdk/+/6271d26baf498ab2b053c52527bc65f7f43e802b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "896e535fe92e96e0b78f64f1587a58b3411c3680"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@896e535fe92e96e0b78f64f1587a58b3411c3680",
"title": "896e535fe92e96e0b78f64f1587a58b3411c3680",
"url": "https://dart.googlesource.com/sdk/+/896e535fe92e96e0b78f64f1587a58b3411c3680"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "a9fe60b1f933ed9ed28589c06939dcae57953652"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@a9fe60b1f933ed9ed28589c06939dcae57953652",
"title": "a9fe60b1f933ed9ed28589c06939dcae57953652",
"url": "https://dart.googlesource.com/sdk/+/a9fe60b1f933ed9ed28589c06939dcae57953652"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "e6d5d32d3b70a31fb97f16d44f1904cb1d182a94"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@e6d5d32d3b70a31fb97f16d44f1904cb1d182a94",
"title": "e6d5d32d3b70a31fb97f16d44f1904cb1d182a94",
"url": "https://dart.googlesource.com/sdk/+/e6d5d32d3b70a31fb97f16d44f1904cb1d182a94"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "8a39d781ce517846d298bc80ea4fdf2863b40b1f"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@8a39d781ce517846d298bc80ea4fdf2863b40b1f",
"title": "8a39d781ce517846d298bc80ea4fdf2863b40b1f",
"url": "https://dart.googlesource.com/sdk/+/8a39d781ce517846d298bc80ea4fdf2863b40b1f"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "8a39d781ce517846d298bc80ea4fdf2863b40b1f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8955129585080485312"
},
{
"key": "scheduler_job_id",
"value": "dart/vm-appjit-linux-debug-x64"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:58:59.150] Scheduled build:
{
"id": "8789699522441618689",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-appjit-linux-debug-x64"
},
"number": 21,
"createdBy": "project:dart",
"createTime": "2023-02-08T19:58:58.727399684Z",
"updateTime": "2023-02-08T19:58:58.727399684Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "8a39d781ce517846d298bc80ea4fdf2863b40b1f",
"ref": "refs/heads/main"
}
}
}
[19:58:59.150] Task URL: https://cr-buildbucket.appspot.com/build/8789699522441618689
[19:58:59.150] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:2:0) after 3m5s
[20:02:04.447] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:2:0)
[20:02:04.471] Build status: SCHEDULED
[20:02:04.471] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:3:0) after 6m43s
[20:08:47.471] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:3:0)
[20:08:47.496] Build status: SCHEDULED
[20:08:47.496] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:4:0) after 3m18s
[20:12:05.513] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:4:0)
[20:12:05.574] Build status: SCHEDULED
[20:12:05.574] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:5:0) after 9m27s
[20:21:32.589] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:5:0)
[20:21:32.612] Build status: SCHEDULED
[20:21:32.612] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:6:0) after 9m20s
[20:30:52.710] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:6:0)
[20:30:52.749] Build status: SCHEDULED
[20:30:52.749] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:7:0) after 7m9s
[20:38:01.737] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:7:0)
[20:38:01.767] Build status: SCHEDULED
[20:38:01.767] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:8:0) after 9m42s
[20:47:43.789] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:8:0)
[20:47:43.818] Build status: SCHEDULED
[20:47:43.818] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:9:0) after 8m21s
[20:56:05.066] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:9:0)
[20:56:05.109] Build status: SCHEDULED
[20:56:05.109] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:10:0) after 6m17s
[21:02:22.279] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:10:0)
[21:02:22.333] Build status: SCHEDULED
[21:02:22.333] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:11:0) after 2m40s
[21:05:02.455] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:11:0)
[21:05:02.484] Build status: SCHEDULED
[21:05:02.484] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:12:0) after 4m40s
[21:09:42.476] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:12:0)
[21:09:42.501] Build status: SCHEDULED
[21:09:42.501] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:13:0) after 4m43s
[21:14:25.620] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:13:0)
[21:14:25.641] Build status: SCHEDULED
[21:14:25.641] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:14:0) after 9m56s
[21:24:21.632] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:14:0)
[21:24:21.659] Build status: SCHEDULED
[21:24:21.659] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:15:0) after 5m11s
[21:29:32.656] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:15:0)
[21:29:32.703] Build status: SCHEDULED
[21:29:32.703] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:16:0) after 2m20s
[21:31:52.729] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:16:0)
[21:31:52.771] Build status: SCHEDULED
[21:31:52.771] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:17:0) after 8m55s
[21:40:47.788] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:17:0)
[21:40:47.821] Build status: SCHEDULED
[21:40:47.821] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:18:0) after 1m51s
[21:42:38.842] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:18:0)
[21:42:38.865] Build status: SCHEDULED
[21:42:38.865] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:19:0) after 4m2s
[21:46:40.888] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:19:0)
[21:46:40.909] Build status: SCHEDULED
[21:46:40.909] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:20:0) after 4m59s
[21:51:39.931] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:20:0)
[21:51:39.952] Build status: SCHEDULED
[21:51:39.952] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:21:0) after 1m54s
[21:53:33.969] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:21:0)
[21:53:33.994] Build status: SCHEDULED
[21:53:33.994] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:22:0) after 7m44s
[22:01:18.012] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:22:0)
[22:01:18.073] Build status: SCHEDULED
[22:01:18.073] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:23:0) after 4m37s
[22:05:55.145] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:23:0)
[22:05:55.170] Build status: SCHEDULED
[22:05:55.170] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:24:0) after 1m56s
[22:07:51.309] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:24:0)
[22:07:51.336] Build status: SCHEDULED
[22:07:51.336] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:25:0) after 6m54s
[22:14:45.511] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:25:0)
[22:14:45.534] Build status: SCHEDULED
[22:14:45.534] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:26:0) after 4m32s
[22:19:17.611] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:26:0)
[22:19:17.634] Build status: SCHEDULED
[22:19:17.634] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:27:0) after 1m36s
[22:20:53.657] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:27:0)
[22:20:53.683] Build status: SCHEDULED
[22:20:53.683] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:28:0) after 9m3s
[22:29:57.048] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:28:0)
[22:29:57.083] Build status: SCHEDULED
[22:29:57.083] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:29:0) after 7m36s
[22:37:33.131] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:29:0)
[22:37:33.155] Build status: SCHEDULED
[22:37:33.155] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:30:0) after 6m46s
[22:44:19.533] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:30:0)
[22:44:19.555] Build status: SCHEDULED
[22:44:19.555] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:31:0) after 5m12s
[22:49:31.769] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:31:0)
[22:49:31.792] Build status: SCHEDULED
[22:49:31.792] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:32:0) after 4m40s
[22:54:11.813] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:32:0)
[22:54:11.836] Build status: SCHEDULED
[22:54:11.836] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:33:0) after 6m53s
[23:01:04.918] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:33:0)
[23:01:05.041] Build status: SCHEDULED
[23:01:05.041] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:34:0) after 6m59s
[23:08:04.068] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:34:0)
[23:08:04.142] Build status: SCHEDULED
[23:08:04.142] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:35:0) after 5m7s
[23:13:11.162] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:35:0)
[23:13:11.184] Build status: SCHEDULED
[23:13:11.184] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:36:0) after 8m56s
[23:22:07.216] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:36:0)
[23:22:07.260] Build status: SCHEDULED
[23:22:07.260] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:37:0) after 9m12s
[23:31:19.331] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:37:0)
[23:31:19.427] Build status: SCHEDULED
[23:31:19.427] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:38:0) after 5m29s
[23:36:48.526] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:38:0)
[23:36:48.547] Build status: SCHEDULED
[23:36:48.547] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:39:0) after 6m2s
[23:42:50.595] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:39:0)
[23:42:50.616] Build status: SCHEDULED
[23:42:50.616] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:40:0) after 6m16s
[23:42:56.914] Received PubSub notification, asking Buildbucket for the build status
[23:42:56.934] Build status: STARTED
[23:49:06.661] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:40:0)
[23:49:06.683] Build status: STARTED
[23:49:06.683] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:42:0) after 4m5s
[23:53:11.710] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:42:0)
[23:53:11.735] Build status: STARTED
[23:53:11.735] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:43:0) after 7m5s
[00:00:16.780] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:43:0)
[00:00:16.810] Build status: STARTED
[00:00:16.810] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:44:0) after 1m1s
[00:01:17.932] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:44:0)
[00:01:17.962] Build status: STARTED
[00:01:17.962] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:45:0) after 6m7s
[00:07:25.068] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:45:0)
[00:07:25.087] Build status: STARTED
[00:07:25.087] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:46:0) after 3m43s
[00:11:08.159] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:46:0)
[00:11:08.185] Build status: STARTED
[00:11:08.185] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:47:0) after 6m21s
[00:17:29.348] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:47:0)
[00:17:29.443] Build status: STARTED
[00:17:29.443] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:48:0) after 9m47s
[00:27:16.471] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:48:0)
[00:27:16.492] Build status: STARTED
[00:27:16.492] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:49:0) after 1m48s
[00:29:04.572] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:49:0)
[00:29:04.694] Build status: STARTED
[00:29:04.694] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:50:0) after 4m26s
[00:33:30.711] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:50:0)
[00:33:30.765] Build status: STARTED
[00:33:30.765] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:51:0) after 2m38s
[00:36:08.850] Handling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:51:0)
[00:36:08.872] Build status: STARTED
[00:36:08.872] Scheduling timer "check-buildbucket-build-status" (dart/vm-appjit-linux-debug-x64:8955129585080485312:52:0) after 3m9s
[00:36:38.654] Received PubSub notification, asking Buildbucket for the build status
[00:36:38.876] Build:
{
"id": "8789699522441618689",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-appjit-linux-debug-x64"
},
"number": 21,
"createdBy": "project:dart",
"createTime": "2023-02-08T19:58:58.727399684Z",
"startTime": "2023-02-08T23:42:56.179216Z",
"endTime": "2023-02-09T00:36:38.319108731Z",
"updateTime": "2023-02-09T00:36:38.319108731Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "8a39d781ce517846d298bc80ea4fdf2863b40b1f",
"ref": "refs/heads/main"
}
}
}
[00:36:38.876] Invocation finished in 4h37m42.049353803s with status SUCCEEDED