[05:00:09.300] New invocation is queued and will start shortly
[05:00:10.825] Starting the invocation (attempt 1)
[05:00:10.859] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5"
[05:00:10.859] Popped gitiles commit info from properties and tags
[05:00:10.859] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:00:10.860] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:00:10.860] Buildbucket request:
{
"requestId": "8874645429075327056",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-msvc-windows"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8874645429075327056",
"job": "dart/vm-msvc-windows",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "e694eb261aa433157b283c231914c92ae0b45614"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@e694eb261aa433157b283c231914c92ae0b45614",
"title": "e694eb261aa433157b283c231914c92ae0b45614",
"url": "https://dart.googlesource.com/sdk/+/e694eb261aa433157b283c231914c92ae0b45614"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "04e4fd2ddf87e1de25f5573dc02c11922def924d"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@04e4fd2ddf87e1de25f5573dc02c11922def924d",
"title": "04e4fd2ddf87e1de25f5573dc02c11922def924d",
"url": "https://dart.googlesource.com/sdk/+/04e4fd2ddf87e1de25f5573dc02c11922def924d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "bf2870c52b1cc03e2ec682ea5d01d372680da775"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@bf2870c52b1cc03e2ec682ea5d01d372680da775",
"title": "bf2870c52b1cc03e2ec682ea5d01d372680da775",
"url": "https://dart.googlesource.com/sdk/+/bf2870c52b1cc03e2ec682ea5d01d372680da775"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "27c2cfb3f7a4c7e548b10aa338cc2b38bccbad36"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@27c2cfb3f7a4c7e548b10aa338cc2b38bccbad36",
"title": "27c2cfb3f7a4c7e548b10aa338cc2b38bccbad36",
"url": "https://dart.googlesource.com/sdk/+/27c2cfb3f7a4c7e548b10aa338cc2b38bccbad36"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "8a3821656074c7af54648e7678b629bc7a05413c"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@8a3821656074c7af54648e7678b629bc7a05413c",
"title": "8a3821656074c7af54648e7678b629bc7a05413c",
"url": "https://dart.googlesource.com/sdk/+/8a3821656074c7af54648e7678b629bc7a05413c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "bbb5e731c3ddc2f0d875f9550a6a4ac85b7b3915"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@bbb5e731c3ddc2f0d875f9550a6a4ac85b7b3915",
"title": "bbb5e731c3ddc2f0d875f9550a6a4ac85b7b3915",
"url": "https://dart.googlesource.com/sdk/+/bbb5e731c3ddc2f0d875f9550a6a4ac85b7b3915"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "fc345a7ead919eb92561d6d5026a3d7a6d9b9b30"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@fc345a7ead919eb92561d6d5026a3d7a6d9b9b30",
"title": "fc345a7ead919eb92561d6d5026a3d7a6d9b9b30",
"url": "https://dart.googlesource.com/sdk/+/fc345a7ead919eb92561d6d5026a3d7a6d9b9b30"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "7684ae57db134673389500bb400133f5f237a895"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@7684ae57db134673389500bb400133f5f237a895",
"title": "7684ae57db134673389500bb400133f5f237a895",
"url": "https://dart.googlesource.com/sdk/+/7684ae57db134673389500bb400133f5f237a895"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "38752cafd208781c1c7804dfbee5539421d6b1eb"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@38752cafd208781c1c7804dfbee5539421d6b1eb",
"title": "38752cafd208781c1c7804dfbee5539421d6b1eb",
"url": "https://dart.googlesource.com/sdk/+/38752cafd208781c1c7804dfbee5539421d6b1eb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "370bbe28e720832de5a5c6a853382907f2b78851"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@370bbe28e720832de5a5c6a853382907f2b78851",
"title": "370bbe28e720832de5a5c6a853382907f2b78851",
"url": "https://dart.googlesource.com/sdk/+/370bbe28e720832de5a5c6a853382907f2b78851"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "5229f453b76ae8545c196b8688fdde301af87d46"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@5229f453b76ae8545c196b8688fdde301af87d46",
"title": "5229f453b76ae8545c196b8688fdde301af87d46",
"url": "https://dart.googlesource.com/sdk/+/5229f453b76ae8545c196b8688fdde301af87d46"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "df9267de95edd2cb9568abcbcfc603927a7fdfc6"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@df9267de95edd2cb9568abcbcfc603927a7fdfc6",
"title": "df9267de95edd2cb9568abcbcfc603927a7fdfc6",
"url": "https://dart.googlesource.com/sdk/+/df9267de95edd2cb9568abcbcfc603927a7fdfc6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "6a5c577465f4ac58343adc8686126f7693afab06"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@6a5c577465f4ac58343adc8686126f7693afab06",
"title": "6a5c577465f4ac58343adc8686126f7693afab06",
"url": "https://dart.googlesource.com/sdk/+/6a5c577465f4ac58343adc8686126f7693afab06"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5",
"title": "615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5",
"url": "https://dart.googlesource.com/sdk/+/615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874645429075327056"
},
{
"key": "scheduler_job_id",
"value": "dart/vm-msvc-windows"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:00:11.283] Scheduled build:
{
"id": "8709215366716729729",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-msvc-windows"
},
"number": 711,
"createdBy": "project:dart",
"createTime": "2025-07-16T05:00:10.919297869Z",
"updateTime": "2025-07-16T05:00:10.919297869Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5",
"ref": "refs/heads/main"
}
}
}
[05:00:11.283] Task URL: https://cr-buildbucket.appspot.com/build/8709215366716729729
[05:00:11.283] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:2:0) after 6m41s
[05:00:11.847] Received PubSub notification, asking Buildbucket for the build status
[05:00:11.868] Build status: SCHEDULED
[05:04:25.134] Received PubSub notification, asking Buildbucket for the build status
[05:04:25.163] Build status: STARTED
[05:06:52.319] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:2:0)
[05:06:52.395] Build status: STARTED
[05:06:52.395] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:5:0) after 6m52s
[05:13:44.873] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:5:0)
[05:13:44.903] Build status: STARTED
[05:13:44.903] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:6:0) after 8m22s
[05:22:07.072] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:6:0)
[05:22:07.109] Build status: STARTED
[05:22:07.109] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:7:0) after 5m39s
[05:27:46.139] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:7:0)
[05:27:46.196] Build status: STARTED
[05:27:46.196] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:8:0) after 4m57s
[05:32:43.233] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:8:0)
[05:32:43.255] Build status: STARTED
[05:32:43.256] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:9:0) after 2m21s
[05:35:04.324] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:9:0)
[05:35:04.349] Build status: STARTED
[05:35:04.349] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:10:0) after 8m51s
[05:43:55.475] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:10:0)
[05:43:55.565] Build status: STARTED
[05:43:55.565] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:11:0) after 2m8s
[05:46:03.588] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:11:0)
[05:46:03.617] Build status: STARTED
[05:46:03.617] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:12:0) after 9m33s
[05:55:36.638] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:12:0)
[05:55:36.694] Build status: STARTED
[05:55:36.694] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:13:0) after 9m38s
[06:05:14.862] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:13:0)
[06:05:14.939] Build status: STARTED
[06:05:14.939] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:14:0) after 7m6s
[06:12:20.973] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:14:0)
[06:12:20.991] Build status: STARTED
[06:12:20.991] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:15:0) after 5m28s
[06:17:49.203] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:15:0)
[06:17:49.235] Build status: STARTED
[06:17:49.235] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:16:0) after 4m12s
[06:22:01.409] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:16:0)
[06:22:01.452] Build status: STARTED
[06:22:01.453] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:17:0) after 9m1s
[06:31:02.531] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:17:0)
[06:31:02.556] Build status: STARTED
[06:31:02.556] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:18:0) after 3m2s
[06:34:04.630] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:18:0)
[06:34:04.651] Build status: STARTED
[06:34:04.651] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:19:0) after 7m12s
[06:41:17.016] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:19:0)
[06:41:17.035] Build status: STARTED
[06:41:17.035] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:20:0) after 8m1s
[06:49:18.056] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:20:0)
[06:49:18.076] Build status: STARTED
[06:49:18.076] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:21:0) after 2m18s
[06:51:36.256] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:21:0)
[06:51:36.280] Build status: STARTED
[06:51:36.280] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:22:0) after 3m49s
[06:55:25.379] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:22:0)
[06:55:25.403] Build status: STARTED
[06:55:25.403] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:23:0) after 2m26s
[06:57:51.423] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:23:0)
[06:57:51.454] Build status: STARTED
[06:57:51.454] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:24:0) after 3m32s
[07:01:23.506] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:24:0)
[07:01:23.656] Build status: STARTED
[07:01:23.656] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:25:0) after 8m46s
[07:10:09.672] Handling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:25:0)
[07:10:09.697] Build status: STARTED
[07:10:09.697] Scheduling timer "check-buildbucket-build-status" (dart/vm-msvc-windows:8874645429075327056:26:0) after 9m56s
[07:17:05.904] Received PubSub notification, asking Buildbucket for the build status
[07:17:05.938] Build:
{
"id": "8709215366716729729",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "vm-msvc-windows"
},
"number": 711,
"createdBy": "project:dart",
"createTime": "2025-07-16T05:00:10.919297869Z",
"startTime": "2025-07-16T05:04:24.766487017Z",
"endTime": "2025-07-16T07:17:05.666858887Z",
"updateTime": "2025-07-16T07:17:05.666858887Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "615e1d1e641f2086e2b3a60c97b5dfa78ae74ac5",
"ref": "refs/heads/main"
}
}
}
[07:17:05.938] Invocation finished in 2h16m56.652451296s with status SUCCEEDED