[05:00:13.502] New invocation is queued and will start shortly
[05:00:16.305] Starting the invocation (attempt 1)
[05:00:16.336] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/4227821323f7ce338fc6f3c3f9fdbbb786882d07"
[05:00:16.336] Popped gitiles commit info from properties and tags
[05:00:16.336] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:00:16.336] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:00:16.337] Buildbucket request:
{
"requestId": "8888959745358184576",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-gcc-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888959745358184576",
"job": "dart/vm-gcc-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "19622c5238bfeab0b7ebe45ae2ebea237e7a57f1"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@19622c5238bfeab0b7ebe45ae2ebea237e7a57f1",
"title": "19622c5238bfeab0b7ebe45ae2ebea237e7a57f1",
"url": "https://dart.googlesource.com/sdk/+/19622c5238bfeab0b7ebe45ae2ebea237e7a57f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "4c0121f2d3dd7ee33776d83f85f6ff1403aefc92"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@4c0121f2d3dd7ee33776d83f85f6ff1403aefc92",
"title": "4c0121f2d3dd7ee33776d83f85f6ff1403aefc92",
"url": "https://dart.googlesource.com/sdk/+/4c0121f2d3dd7ee33776d83f85f6ff1403aefc92"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "2c81261f941db1717c2b975ef8395a96ca3b97b4"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@2c81261f941db1717c2b975ef8395a96ca3b97b4",
"title": "2c81261f941db1717c2b975ef8395a96ca3b97b4",
"url": "https://dart.googlesource.com/sdk/+/2c81261f941db1717c2b975ef8395a96ca3b97b4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "e0b0c33937e783f8420884fdd4a7f3305640df47"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@e0b0c33937e783f8420884fdd4a7f3305640df47",
"title": "e0b0c33937e783f8420884fdd4a7f3305640df47",
"url": "https://dart.googlesource.com/sdk/+/e0b0c33937e783f8420884fdd4a7f3305640df47"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "89e545c5cc939f5861faace1d78252fd2aca834f"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@89e545c5cc939f5861faace1d78252fd2aca834f",
"title": "89e545c5cc939f5861faace1d78252fd2aca834f",
"url": "https://dart.googlesource.com/sdk/+/89e545c5cc939f5861faace1d78252fd2aca834f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "89af2425f23bfb127ab39c2f73e9149042715443"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@89af2425f23bfb127ab39c2f73e9149042715443",
"title": "89af2425f23bfb127ab39c2f73e9149042715443",
"url": "https://dart.googlesource.com/sdk/+/89af2425f23bfb127ab39c2f73e9149042715443"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "85724ebdaf02eb2895afea028bcbd8670b6f3239"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@85724ebdaf02eb2895afea028bcbd8670b6f3239",
"title": "85724ebdaf02eb2895afea028bcbd8670b6f3239",
"url": "https://dart.googlesource.com/sdk/+/85724ebdaf02eb2895afea028bcbd8670b6f3239"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "46a739ce206b19ba32af62ed0ab6020fa3254d9e"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@46a739ce206b19ba32af62ed0ab6020fa3254d9e",
"title": "46a739ce206b19ba32af62ed0ab6020fa3254d9e",
"url": "https://dart.googlesource.com/sdk/+/46a739ce206b19ba32af62ed0ab6020fa3254d9e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "a78fb5e47a95e251fd8f6dec034fb01e71e87bf0"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@a78fb5e47a95e251fd8f6dec034fb01e71e87bf0",
"title": "a78fb5e47a95e251fd8f6dec034fb01e71e87bf0",
"url": "https://dart.googlesource.com/sdk/+/a78fb5e47a95e251fd8f6dec034fb01e71e87bf0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "c87b18581a58f7fbd5887023ddf7f5afb498e5bd"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@c87b18581a58f7fbd5887023ddf7f5afb498e5bd",
"title": "c87b18581a58f7fbd5887023ddf7f5afb498e5bd",
"url": "https://dart.googlesource.com/sdk/+/c87b18581a58f7fbd5887023ddf7f5afb498e5bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "9a478621da5035833f0deb6a04f2a5c2dd7f8066"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@9a478621da5035833f0deb6a04f2a5c2dd7f8066",
"title": "9a478621da5035833f0deb6a04f2a5c2dd7f8066",
"url": "https://dart.googlesource.com/sdk/+/9a478621da5035833f0deb6a04f2a5c2dd7f8066"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "da2ffedc507ca67fe3cd5c3861b972e8f8f6ac38"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@da2ffedc507ca67fe3cd5c3861b972e8f8f6ac38",
"title": "da2ffedc507ca67fe3cd5c3861b972e8f8f6ac38",
"url": "https://dart.googlesource.com/sdk/+/da2ffedc507ca67fe3cd5c3861b972e8f8f6ac38"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "7a22fea4bcd945a83d4333fc7d893c054328b349"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@7a22fea4bcd945a83d4333fc7d893c054328b349",
"title": "7a22fea4bcd945a83d4333fc7d893c054328b349",
"url": "https://dart.googlesource.com/sdk/+/7a22fea4bcd945a83d4333fc7d893c054328b349"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "e3d809f1c2104b6850427687803f88406afcce9e"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@e3d809f1c2104b6850427687803f88406afcce9e",
"title": "e3d809f1c2104b6850427687803f88406afcce9e",
"url": "https://dart.googlesource.com/sdk/+/e3d809f1c2104b6850427687803f88406afcce9e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "c040cd66e521ae55a6ef6a3d996a170ac4d9d32f"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@c040cd66e521ae55a6ef6a3d996a170ac4d9d32f",
"title": "c040cd66e521ae55a6ef6a3d996a170ac4d9d32f",
"url": "https://dart.googlesource.com/sdk/+/c040cd66e521ae55a6ef6a3d996a170ac4d9d32f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "c3a4f56e692224ba85f5808ede2855de906ca369"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@c3a4f56e692224ba85f5808ede2855de906ca369",
"title": "c3a4f56e692224ba85f5808ede2855de906ca369",
"url": "https://dart.googlesource.com/sdk/+/c3a4f56e692224ba85f5808ede2855de906ca369"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "77e2e9461ad14a06f3ee429186c12ad50afca84a"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@77e2e9461ad14a06f3ee429186c12ad50afca84a",
"title": "77e2e9461ad14a06f3ee429186c12ad50afca84a",
"url": "https://dart.googlesource.com/sdk/+/77e2e9461ad14a06f3ee429186c12ad50afca84a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "4227821323f7ce338fc6f3c3f9fdbbb786882d07"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@4227821323f7ce338fc6f3c3f9fdbbb786882d07",
"title": "4227821323f7ce338fc6f3c3f9fdbbb786882d07",
"url": "https://dart.googlesource.com/sdk/+/4227821323f7ce338fc6f3c3f9fdbbb786882d07"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "4227821323f7ce338fc6f3c3f9fdbbb786882d07",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888959745358184576"
},
{
"key": "scheduler_job_id",
"value": "dart/vm-gcc-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:00:16.957] Scheduled build:
{
"id": "8723529681636280529",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-gcc-linux"
},
"number": 565,
"createdBy": "project:dart",
"createTime": "2025-02-08T05:00:16.423776815Z",
"updateTime": "2025-02-08T05:00:16.423776815Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "4227821323f7ce338fc6f3c3f9fdbbb786882d07",
"ref": "refs/heads/main"
}
}
}
[05:00:16.957] Task URL: https://cr-buildbucket.appspot.com/build/8723529681636280529
[05:00:16.957] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:2:0) after 8m57s
[05:00:21.884] Received PubSub notification, asking Buildbucket for the build status
[05:00:21.910] Build status: SCHEDULED
[05:00:41.985] Received PubSub notification, asking Buildbucket for the build status
[05:00:42.001] Build status: STARTED
[05:09:14.034] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:2:0)
[05:09:14.067] Build status: STARTED
[05:09:14.067] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:5:0) after 2m35s
[05:11:49.089] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:5:0)
[05:11:49.107] Build status: STARTED
[05:11:49.107] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:6:0) after 8m31s
[05:20:20.179] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:6:0)
[05:20:20.205] Build status: STARTED
[05:20:20.205] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:7:0) after 2m32s
[05:22:52.689] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:7:0)
[05:22:52.722] Build status: STARTED
[05:22:52.722] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:8:0) after 8m54s
[05:31:46.747] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:8:0)
[05:31:46.775] Build status: STARTED
[05:31:46.775] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:9:0) after 1m53s
[05:33:39.858] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:9:0)
[05:33:39.910] Build status: STARTED
[05:33:39.911] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:10:0) after 6m51s
[05:40:30.930] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:10:0)
[05:40:30.956] Build status: STARTED
[05:40:30.956] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:11:0) after 7m34s
[05:48:04.976] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:11:0)
[05:48:04.999] Build status: STARTED
[05:48:04.999] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:12:0) after 2m33s
[05:50:38.023] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:12:0)
[05:50:38.048] Build status: STARTED
[05:50:38.048] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:13:0) after 1m59s
[05:52:37.063] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:13:0)
[05:52:37.096] Build status: STARTED
[05:52:37.096] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:14:0) after 7m50s
[06:00:27.209] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:14:0)
[06:00:27.231] Build status: STARTED
[06:00:27.231] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:15:0) after 5m37s
[06:06:04.317] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:15:0)
[06:06:04.340] Build status: STARTED
[06:06:04.340] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:16:0) after 1m44s
[06:07:48.363] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:16:0)
[06:07:48.392] Build status: STARTED
[06:07:48.392] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:17:0) after 4m48s
[06:12:36.592] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:17:0)
[06:12:36.650] Build status: STARTED
[06:12:36.650] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:18:0) after 5m55s
[06:18:31.812] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:18:0)
[06:18:31.868] Build status: STARTED
[06:18:31.868] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:19:0) after 3m42s
[06:22:14.515] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:19:0)
[06:22:14.780] Build status: STARTED
[06:22:14.780] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:20:0) after 9m8s
[06:31:22.906] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:20:0)
[06:31:22.966] Build status: STARTED
[06:31:22.966] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:21:0) after 8m39s
[06:40:02.133] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:21:0)
[06:40:02.163] Build status: STARTED
[06:40:02.163] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:22:0) after 1m25s
[06:41:27.442] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:22:0)
[06:41:27.481] Build status: STARTED
[06:41:27.481] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:23:0) after 2m29s
[06:43:56.463] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:23:0)
[06:43:56.500] Build status: STARTED
[06:43:56.500] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:24:0) after 9m19s
[06:53:15.532] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:24:0)
[06:53:15.550] Build status: STARTED
[06:53:15.550] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:25:0) after 5m6s
[06:58:21.951] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:25:0)
[06:58:21.970] Build status: STARTED
[06:58:21.970] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:26:0) after 2m45s
[07:01:07.070] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:26:0)
[07:01:07.148] Build status: STARTED
[07:01:07.148] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:27:0) after 3m59s
[07:05:06.162] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:27:0)
[07:05:06.187] Build status: STARTED
[07:05:06.187] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:28:0) after 2m58s
[07:08:04.209] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:28:0)
[07:08:04.239] Build status: STARTED
[07:08:04.239] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:29:0) after 4m9s
[07:12:13.262] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:29:0)
[07:12:13.292] Build status: STARTED
[07:12:13.292] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:30:0) after 8m3s
[07:20:16.318] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:30:0)
[07:20:16.334] Build status: STARTED
[07:20:16.334] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:31:0) after 4m49s
[07:25:05.515] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:31:0)
[07:25:05.541] Build status: STARTED
[07:25:05.541] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:32:0) after 3m14s
[07:28:19.564] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:32:0)
[07:28:19.601] Build status: STARTED
[07:28:19.601] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:33:0) after 3m24s
[07:31:43.641] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:33:0)
[07:31:43.659] Build status: STARTED
[07:31:43.659] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:34:0) after 9m51s
[07:41:34.648] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:34:0)
[07:41:34.676] Build status: STARTED
[07:41:34.676] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:35:0) after 3m53s
[07:45:27.850] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:35:0)
[07:45:27.868] Build status: STARTED
[07:45:27.868] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8888959745358184576:36:0) after 9m15s
[07:54:31.780] Received PubSub notification, asking Buildbucket for the build status
[07:54:31.811] Build:
{
"id": "8723529681636280529",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-gcc-linux"
},
"number": 565,
"createdBy": "project:dart",
"createTime": "2025-02-08T05:00:16.423776815Z",
"startTime": "2025-02-08T05:00:39.550575299Z",
"endTime": "2025-02-08T07:54:31.486084075Z",
"updateTime": "2025-02-08T07:54:31.486084075Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "4227821323f7ce338fc6f3c3f9fdbbb786882d07",
"ref": "refs/heads/main"
}
}
}
[07:54:31.812] Invocation finished in 2h54m18.321710066s with status SUCCEEDED