[18:16:20.513] New invocation is queued and will start shortly
[18:16:21.718] Starting the invocation (attempt 1)
[18:16:21.747] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/sdk/+/e210ee3577b3b356479d4aece3fdffff97d4f30b"
[18:16:21.747] Popped gitiles commit info from properties and tags
[18:16:21.747] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:21.747] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:21.747] Buildbucket request:
{
"requestId": "8956947979785047184",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "gclient"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8956947979785047184",
"job": "dart/gclient",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "ca974e5c4fb1895f880c5c3e0137e5ff96cbb0c5"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@ca974e5c4fb1895f880c5c3e0137e5ff96cbb0c5",
"title": "ca974e5c4fb1895f880c5c3e0137e5ff96cbb0c5",
"url": "https://dart.googlesource.com/sdk/+/ca974e5c4fb1895f880c5c3e0137e5ff96cbb0c5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "84e3c8b50f4453ccf94a2cbdcc47fbb103947fdc"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@84e3c8b50f4453ccf94a2cbdcc47fbb103947fdc",
"title": "84e3c8b50f4453ccf94a2cbdcc47fbb103947fdc",
"url": "https://dart.googlesource.com/sdk/+/84e3c8b50f4453ccf94a2cbdcc47fbb103947fdc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "68ee427e6003d9f5ee585df53bcea08c6a283a0e"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@68ee427e6003d9f5ee585df53bcea08c6a283a0e",
"title": "68ee427e6003d9f5ee585df53bcea08c6a283a0e",
"url": "https://dart.googlesource.com/sdk/+/68ee427e6003d9f5ee585df53bcea08c6a283a0e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "0f065ef477cd9f0c59b04423fb4d6d2f9b8b645b"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@0f065ef477cd9f0c59b04423fb4d6d2f9b8b645b",
"title": "0f065ef477cd9f0c59b04423fb4d6d2f9b8b645b",
"url": "https://dart.googlesource.com/sdk/+/0f065ef477cd9f0c59b04423fb4d6d2f9b8b645b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "b2dff24bd766c2dfe0414bd3038d7380eee6e75e"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@b2dff24bd766c2dfe0414bd3038d7380eee6e75e",
"title": "b2dff24bd766c2dfe0414bd3038d7380eee6e75e",
"url": "https://dart.googlesource.com/sdk/+/b2dff24bd766c2dfe0414bd3038d7380eee6e75e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "2b3715f5fe5bbea59400f47e015e3ef7fac5e1d8"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@2b3715f5fe5bbea59400f47e015e3ef7fac5e1d8",
"title": "2b3715f5fe5bbea59400f47e015e3ef7fac5e1d8",
"url": "https://dart.googlesource.com/sdk/+/2b3715f5fe5bbea59400f47e015e3ef7fac5e1d8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "f9524f85094831ba3813737028047cfc47ed8f07"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@f9524f85094831ba3813737028047cfc47ed8f07",
"title": "f9524f85094831ba3813737028047cfc47ed8f07",
"url": "https://dart.googlesource.com/sdk/+/f9524f85094831ba3813737028047cfc47ed8f07"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/sdk",
"revision": "e210ee3577b3b356479d4aece3fdffff97d4f30b"
},
"id": "https://dart.googlesource.com/sdk/+/refs/heads/main@e210ee3577b3b356479d4aece3fdffff97d4f30b",
"title": "e210ee3577b3b356479d4aece3fdffff97d4f30b",
"url": "https://dart.googlesource.com/sdk/+/e210ee3577b3b356479d4aece3fdffff97d4f30b"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "e210ee3577b3b356479d4aece3fdffff97d4f30b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8956947979785047184"
},
{
"key": "scheduler_job_id",
"value": "dart/gclient"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:16:22.248] Scheduled build:
{
"id": "8791517917749889681",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "gclient"
},
"number": 20179,
"createdBy": "project:dart",
"createTime": "2023-01-19T18:16:21.824237787Z",
"updateTime": "2023-01-19T18:16:21.824237787Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "e210ee3577b3b356479d4aece3fdffff97d4f30b",
"ref": "refs/heads/main"
}
}
}
[18:16:22.248] Task URL: https://cr-buildbucket.appspot.com/build/8791517917749889681
[18:16:22.248] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:2:0) after 3m8s
[18:19:30.268] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:2:0)
[18:19:30.312] Build status: SCHEDULED
[18:19:30.312] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:3:0) after 5m5s
[18:24:35.330] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:3:0)
[18:24:35.349] Build status: SCHEDULED
[18:24:35.349] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:4:0) after 3m41s
[18:28:16.377] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:4:0)
[18:28:16.494] Build status: SCHEDULED
[18:28:16.494] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:5:0) after 8m16s
[18:36:32.550] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:5:0)
[18:36:32.584] Build status: SCHEDULED
[18:36:32.584] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:6:0) after 9m27s
[18:45:59.650] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:6:0)
[18:45:59.674] Build status: SCHEDULED
[18:45:59.674] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:7:0) after 4m51s
[18:50:50.724] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:7:0)
[18:50:50.831] Build status: SCHEDULED
[18:50:50.831] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:8:0) after 2m51s
[18:53:41.859] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:8:0)
[18:53:41.909] Build status: SCHEDULED
[18:53:41.909] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:9:0) after 4m22s
[18:58:03.940] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:9:0)
[18:58:03.965] Build status: SCHEDULED
[18:58:03.965] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:10:0) after 1m57s
[19:00:01.162] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:10:0)
[19:00:01.196] Build status: SCHEDULED
[19:00:01.196] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:11:0) after 9m48s
[19:09:49.263] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:11:0)
[19:09:49.289] Build status: SCHEDULED
[19:09:49.289] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:12:0) after 2m44s
[19:12:33.356] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:12:0)
[19:12:33.380] Build status: SCHEDULED
[19:12:33.380] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:13:0) after 6m4s
[19:18:37.440] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:13:0)
[19:18:37.482] Build status: SCHEDULED
[19:18:37.482] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:14:0) after 6m26s
[19:25:03.509] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:14:0)
[19:25:03.539] Build status: SCHEDULED
[19:25:03.539] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:15:0) after 8m31s
[19:33:34.668] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:15:0)
[19:33:34.693] Build status: SCHEDULED
[19:33:34.693] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:16:0) after 6m56s
[19:40:30.714] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:16:0)
[19:40:30.746] Build status: SCHEDULED
[19:40:30.746] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:17:0) after 1m21s
[19:41:51.819] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:17:0)
[19:41:51.842] Build status: SCHEDULED
[19:41:51.842] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:18:0) after 4m50s
[19:46:41.931] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:18:0)
[19:46:41.958] Build status: SCHEDULED
[19:46:41.958] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:19:0) after 3m51s
[19:50:32.985] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:19:0)
[19:50:33.010] Build status: SCHEDULED
[19:50:33.010] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:20:0) after 6m41s
[19:57:14.089] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:20:0)
[19:57:14.142] Build status: SCHEDULED
[19:57:14.142] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:21:0) after 4m20s
[20:01:34.212] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:21:0)
[20:01:34.238] Build status: SCHEDULED
[20:01:34.239] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:22:0) after 3m40s
[20:05:14.265] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:22:0)
[20:05:14.303] Build status: SCHEDULED
[20:05:14.303] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:23:0) after 7m54s
[20:13:08.379] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:23:0)
[20:13:08.449] Build status: SCHEDULED
[20:13:08.449] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:24:0) after 1m35s
[20:14:43.437] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:24:0)
[20:14:43.456] Build status: SCHEDULED
[20:14:43.456] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:25:0) after 9m29s
[20:24:12.533] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:25:0)
[20:24:12.583] Build status: SCHEDULED
[20:24:12.583] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:26:0) after 8m2s
[20:32:14.679] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:26:0)
[20:32:14.704] Build status: SCHEDULED
[20:32:14.704] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:27:0) after 3m58s
[20:36:12.748] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:27:0)
[20:36:12.787] Build status: SCHEDULED
[20:36:12.787] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:28:0) after 9m28s
[20:45:40.966] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:28:0)
[20:45:40.987] Build status: SCHEDULED
[20:45:40.987] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:29:0) after 9m13s
[20:54:54.107] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:29:0)
[20:54:54.133] Build status: SCHEDULED
[20:54:54.133] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:30:0) after 6m17s
[21:01:11.228] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:30:0)
[21:01:11.326] Build status: SCHEDULED
[21:01:11.326] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:31:0) after 9m35s
[21:10:46.581] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:31:0)
[21:10:46.611] Build status: SCHEDULED
[21:10:46.611] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:32:0) after 7m50s
[21:18:36.807] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:32:0)
[21:18:36.834] Build status: SCHEDULED
[21:18:36.834] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:33:0) after 5m1s
[21:23:38.269] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:33:0)
[21:23:38.292] Build status: SCHEDULED
[21:23:38.292] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:34:0) after 8m41s
[21:32:19.493] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:34:0)
[21:32:19.516] Build status: SCHEDULED
[21:32:19.516] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:35:0) after 6m2s
[21:38:21.521] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:35:0)
[21:38:21.550] Build status: SCHEDULED
[21:38:21.550] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:36:0) after 6m54s
[21:45:15.777] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:36:0)
[21:45:15.816] Build status: SCHEDULED
[21:45:15.817] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:37:0) after 5m42s
[21:50:57.837] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:37:0)
[21:50:57.919] Build status: SCHEDULED
[21:50:57.920] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:38:0) after 2m37s
[21:53:35.152] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:38:0)
[21:53:35.178] Build status: SCHEDULED
[21:53:35.178] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:39:0) after 8m57s
[22:02:32.733] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:39:0)
[22:02:32.758] Build status: SCHEDULED
[22:02:32.758] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:40:0) after 5m12s
[22:07:45.046] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:40:0)
[22:07:45.115] Build status: SCHEDULED
[22:07:45.115] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:41:0) after 7m2s
[22:14:47.164] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:41:0)
[22:14:47.190] Build status: SCHEDULED
[22:14:47.190] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:42:0) after 4m52s
[22:15:02.555] Received PubSub notification, asking Buildbucket for the build status
[22:15:02.605] Build status: STARTED
[22:19:39.510] Handling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:42:0)
[22:19:39.535] Build status: STARTED
[22:19:39.535] Scheduling timer "check-buildbucket-build-status" (dart/gclient:8956947979785047184:44:0) after 6m4s
[22:20:39.564] Received PubSub notification, asking Buildbucket for the build status
[22:20:39.588] Build:
{
"id": "8791517917749889681",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "gclient"
},
"number": 20179,
"createdBy": "project:dart",
"createTime": "2023-01-19T18:16:21.824237787Z",
"startTime": "2023-01-19T22:15:00.680767Z",
"endTime": "2023-01-19T22:20:38.818538929Z",
"updateTime": "2023-01-19T22:20:38.818538929Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "sdk",
"id": "e210ee3577b3b356479d4aece3fdffff97d4f30b",
"ref": "refs/heads/main"
}
}
}
[22:20:39.588] Invocation finished in 4h4m19.088057877s with status SUCCEEDED