[05:00:06.798] New invocation is queued and will start shortly
[05:00:08.453] Starting the invocation (attempt 1)
[05:00:08.479] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1"
[05:00:08.479] Popped gitiles commit info from properties and tags
[05:00:08.479] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:00:08.479] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:00:08.486] Buildbucket request:
{
"requestId": "8936160771879089760",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-gcc-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8936160771879089760",
"job": "dart/vm-gcc-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "783b1aa64ac84f5cab7e2140da1965dab0fb74c8"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@783b1aa64ac84f5cab7e2140da1965dab0fb74c8",
"title": "783b1aa64ac84f5cab7e2140da1965dab0fb74c8",
"url": "https://dart.googlesource.com/sdk/+/783b1aa64ac84f5cab7e2140da1965dab0fb74c8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "82bd6a2e43b8d2b1166f2f3091e8c511301fe76d"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@82bd6a2e43b8d2b1166f2f3091e8c511301fe76d",
"title": "82bd6a2e43b8d2b1166f2f3091e8c511301fe76d",
"url": "https://dart.googlesource.com/sdk/+/82bd6a2e43b8d2b1166f2f3091e8c511301fe76d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "cf5336c2449d134c268ee2ed5aeb29048a37870f"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@cf5336c2449d134c268ee2ed5aeb29048a37870f",
"title": "cf5336c2449d134c268ee2ed5aeb29048a37870f",
"url": "https://dart.googlesource.com/sdk/+/cf5336c2449d134c268ee2ed5aeb29048a37870f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "2a669c571f2bf66de2099d3ca2201a7365bf692c"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@2a669c571f2bf66de2099d3ca2201a7365bf692c",
"title": "2a669c571f2bf66de2099d3ca2201a7365bf692c",
"url": "https://dart.googlesource.com/sdk/+/2a669c571f2bf66de2099d3ca2201a7365bf692c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "b94d3f730d1004dbf51449c2139ffc5c07583469"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@b94d3f730d1004dbf51449c2139ffc5c07583469",
"title": "b94d3f730d1004dbf51449c2139ffc5c07583469",
"url": "https://dart.googlesource.com/sdk/+/b94d3f730d1004dbf51449c2139ffc5c07583469"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "032c2d75f160f941bf87a01fb2f835b7e353ca74"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@032c2d75f160f941bf87a01fb2f835b7e353ca74",
"title": "032c2d75f160f941bf87a01fb2f835b7e353ca74",
"url": "https://dart.googlesource.com/sdk/+/032c2d75f160f941bf87a01fb2f835b7e353ca74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "cfcd047658d5d1554ce4efa3ecbb39331315ebd8"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@cfcd047658d5d1554ce4efa3ecbb39331315ebd8",
"title": "cfcd047658d5d1554ce4efa3ecbb39331315ebd8",
"url": "https://dart.googlesource.com/sdk/+/cfcd047658d5d1554ce4efa3ecbb39331315ebd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "ee2bc9d870aa8c3ce7d94526078ef18ad93da5ee"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@ee2bc9d870aa8c3ce7d94526078ef18ad93da5ee",
"title": "ee2bc9d870aa8c3ce7d94526078ef18ad93da5ee",
"url": "https://dart.googlesource.com/sdk/+/ee2bc9d870aa8c3ce7d94526078ef18ad93da5ee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "32cff8fe007e3db3d0dd39db22eff723de224e91"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@32cff8fe007e3db3d0dd39db22eff723de224e91",
"title": "32cff8fe007e3db3d0dd39db22eff723de224e91",
"url": "https://dart.googlesource.com/sdk/+/32cff8fe007e3db3d0dd39db22eff723de224e91"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "5e60f8816a36134d5a4b24437f63521673820c99"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@5e60f8816a36134d5a4b24437f63521673820c99",
"title": "5e60f8816a36134d5a4b24437f63521673820c99",
"url": "https://dart.googlesource.com/sdk/+/5e60f8816a36134d5a4b24437f63521673820c99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "675a83240bb3fcbd2300d6539a60b6a5caf62cf5"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@675a83240bb3fcbd2300d6539a60b6a5caf62cf5",
"title": "675a83240bb3fcbd2300d6539a60b6a5caf62cf5",
"url": "https://dart.googlesource.com/sdk/+/675a83240bb3fcbd2300d6539a60b6a5caf62cf5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "72e8b555aa192c393cbf9325e05c7a11a9c76918"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@72e8b555aa192c393cbf9325e05c7a11a9c76918",
"title": "72e8b555aa192c393cbf9325e05c7a11a9c76918",
"url": "https://dart.googlesource.com/sdk/+/72e8b555aa192c393cbf9325e05c7a11a9c76918"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "8b601a1cd2c2013613f28909245e2d03cf90712d"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@8b601a1cd2c2013613f28909245e2d03cf90712d",
"title": "8b601a1cd2c2013613f28909245e2d03cf90712d",
"url": "https://dart.googlesource.com/sdk/+/8b601a1cd2c2013613f28909245e2d03cf90712d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "f064b6149b8d6f63950dbf001cdcba6fae040e3c"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@f064b6149b8d6f63950dbf001cdcba6fae040e3c",
"title": "f064b6149b8d6f63950dbf001cdcba6fae040e3c",
"url": "https://dart.googlesource.com/sdk/+/f064b6149b8d6f63950dbf001cdcba6fae040e3c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "ebc96ecb8325149da30145a66d3bd16eda8968b2"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@ebc96ecb8325149da30145a66d3bd16eda8968b2",
"title": "ebc96ecb8325149da30145a66d3bd16eda8968b2",
"url": "https://dart.googlesource.com/sdk/+/ebc96ecb8325149da30145a66d3bd16eda8968b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "b8ee3a999676b0e502c0a2a9cba9730374d9bf83"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@b8ee3a999676b0e502c0a2a9cba9730374d9bf83",
"title": "b8ee3a999676b0e502c0a2a9cba9730374d9bf83",
"url": "https://dart.googlesource.com/sdk/+/b8ee3a999676b0e502c0a2a9cba9730374d9bf83"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "53fb57ea5e97608c4f96cf5c3855eff24e720d19"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@53fb57ea5e97608c4f96cf5c3855eff24e720d19",
"title": "53fb57ea5e97608c4f96cf5c3855eff24e720d19",
"url": "https://dart.googlesource.com/sdk/+/53fb57ea5e97608c4f96cf5c3855eff24e720d19"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1",
"title": "4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1",
"url": "https://dart.googlesource.com/sdk/+/4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8936160771879089760"
},
{
"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:09.106] Scheduled build:
{
"id": "8770730709368418449",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-gcc-linux"
},
"number": 134,
"createdBy": "project:dart",
"createTime": "2023-09-06T05:00:08.567412670Z",
"updateTime": "2023-09-06T05:00:08.567412670Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1",
"ref": "refs/heads/main"
}
}
}
[05:00:09.106] Task URL: https://cr-buildbucket.appspot.com/build/8770730709368418449
[05:00:09.106] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:2:0) after 2m16s
[05:00:10.700] Received PubSub notification, asking Buildbucket for the build status
[05:00:10.720] Build status: SCHEDULED
[05:00:21.943] Received PubSub notification, asking Buildbucket for the build status
[05:00:21.986] Build status: STARTED
[05:02:25.133] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:2:0)
[05:02:25.173] Build status: STARTED
[05:02:25.173] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:5:0) after 3m23s
[05:05:48.292] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:5:0)
[05:05:48.319] Build status: STARTED
[05:05:48.319] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:6:0) after 1m28s
[05:07:16.482] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:6:0)
[05:07:16.505] Build status: STARTED
[05:07:16.506] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:7:0) after 2m50s
[05:10:06.625] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:7:0)
[05:10:06.655] Build status: STARTED
[05:10:06.655] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:8:0) after 9m13s
[05:19:19.675] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:8:0)
[05:19:19.719] Build status: STARTED
[05:19:19.719] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:9:0) after 9m38s
[05:28:57.880] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:9:0)
[05:28:57.903] Build status: STARTED
[05:28:57.903] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:10:0) after 8m56s
[05:37:53.917] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:10:0)
[05:37:53.938] Build status: STARTED
[05:37:53.938] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:11:0) after 1m47s
[05:39:40.957] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:11:0)
[05:39:40.980] Build status: STARTED
[05:39:40.980] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:12:0) after 2m22s
[05:42:03.098] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:12:0)
[05:42:03.114] Build status: STARTED
[05:42:03.114] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:13:0) after 6m3s
[05:48:06.255] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:13:0)
[05:48:06.273] Build status: STARTED
[05:48:06.273] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:14:0) after 1m18s
[05:49:24.319] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:14:0)
[05:49:24.340] Build status: STARTED
[05:49:24.340] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:15:0) after 5m20s
[05:54:44.364] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:15:0)
[05:54:44.384] Build status: STARTED
[05:54:44.384] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:16:0) after 9m55s
[06:04:39.409] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:16:0)
[06:04:39.434] Build status: STARTED
[06:04:39.434] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:17:0) after 1m34s
[06:06:13.610] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:17:0)
[06:06:13.628] Build status: STARTED
[06:06:13.628] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:18:0) after 3m29s
[06:09:42.794] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:18:0)
[06:09:42.833] Build status: STARTED
[06:09:42.833] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:19:0) after 4m24s
[06:14:06.840] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:19:0)
[06:14:06.919] Build status: STARTED
[06:14:06.919] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:20:0) after 3m45s
[06:17:51.938] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:20:0)
[06:17:52.069] Build status: STARTED
[06:17:52.069] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:21:0) after 6m32s
[06:24:24.085] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:21:0)
[06:24:24.112] Build status: STARTED
[06:24:24.112] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:22:0) after 3m34s
[06:27:58.225] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:22:0)
[06:27:58.244] Build status: STARTED
[06:27:58.244] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:23:0) after 9m22s
[06:37:20.389] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:23:0)
[06:37:20.421] Build status: STARTED
[06:37:20.422] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:24:0) after 5m55s
[06:43:15.427] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:24:0)
[06:43:15.449] Build status: STARTED
[06:43:15.449] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:25:0) after 5m22s
[06:48:37.567] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:25:0)
[06:48:37.586] Build status: STARTED
[06:48:37.586] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:26:0) after 4m32s
[06:53:09.606] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:26:0)
[06:53:09.626] Build status: STARTED
[06:53:09.626] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:27:0) after 6m34s
[06:59:43.679] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:27:0)
[06:59:43.704] Build status: STARTED
[06:59:43.704] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:28:0) after 1m54s
[07:01:37.853] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:28:0)
[07:01:37.877] Build status: STARTED
[07:01:37.877] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:29:0) after 4m36s
[07:06:14.244] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:29:0)
[07:06:14.268] Build status: STARTED
[07:06:14.268] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:30:0) after 1m30s
[07:07:44.373] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:30:0)
[07:07:44.425] Build status: STARTED
[07:07:44.425] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:31:0) after 9m36s
[07:17:20.583] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:31:0)
[07:17:20.608] Build status: STARTED
[07:17:20.608] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:32:0) after 1m22s
[07:18:42.803] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:32:0)
[07:18:42.827] Build status: STARTED
[07:18:42.827] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:33:0) after 7m39s
[07:26:21.847] Handling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:33:0)
[07:26:21.986] Build status: STARTED
[07:26:21.986] Scheduling timer "check-buildbucket-build-status" (dart/vm-gcc-linux:8936160771879089760:34:0) after 2m36s
[07:27:34.034] Received PubSub notification, asking Buildbucket for the build status
[07:27:34.054] Build:
{
"id": "8770730709368418449",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-gcc-linux"
},
"number": 134,
"createdBy": "project:dart",
"createTime": "2023-09-06T05:00:08.567412670Z",
"startTime": "2023-09-06T05:00:20.281894Z",
"endTime": "2023-09-06T07:27:33.762198315Z",
"updateTime": "2023-09-06T07:27:33.762198315Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "4bddf8a25ff894f6a8ac9ee122a1eb2112fd2dd1",
"ref": "refs/heads/main"
}
}
}
[07:27:34.054] Invocation finished in 2h27m27.265195233s with status SUCCEEDED