[23:24:20.580] New invocation is queued and will start shortly
[23:24:25.961] Starting the invocation (attempt 1)
[23:24:25.993] Popped buildset tag "buildset:commit/gitiles/dart.googlesource.com/monorepo/+/da5877629e422abc5235fefde05c8952e7748237"
[23:24:25.993] Popped gitiles commit info from properties and tags
[23:24:25.993] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:24:25.994] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:24:25.995] Buildbucket request:
{
"requestId": "8960190092824199712",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "flutter-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8960190092824199712",
"job": "dart/flutter-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "5e4ab4bbbf70e55f3fdf3169914b41d348fbc189"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@5e4ab4bbbf70e55f3fdf3169914b41d348fbc189",
"title": "5e4ab4bbbf70e55f3fdf3169914b41d348fbc189",
"url": "https://dart.googlesource.com/monorepo//+/5e4ab4bbbf70e55f3fdf3169914b41d348fbc189"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "d3096b94aedf85ff2e518d3ffb47b3bbf8ca6607"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@d3096b94aedf85ff2e518d3ffb47b3bbf8ca6607",
"title": "d3096b94aedf85ff2e518d3ffb47b3bbf8ca6607",
"url": "https://dart.googlesource.com/monorepo//+/d3096b94aedf85ff2e518d3ffb47b3bbf8ca6607"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "4a850aef7e019a24836e0cba12fa71c7e749f7cf"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@4a850aef7e019a24836e0cba12fa71c7e749f7cf",
"title": "4a850aef7e019a24836e0cba12fa71c7e749f7cf",
"url": "https://dart.googlesource.com/monorepo//+/4a850aef7e019a24836e0cba12fa71c7e749f7cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "bd392e3a0c04a0a4de2ed942668690792db02b13"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@bd392e3a0c04a0a4de2ed942668690792db02b13",
"title": "bd392e3a0c04a0a4de2ed942668690792db02b13",
"url": "https://dart.googlesource.com/monorepo//+/bd392e3a0c04a0a4de2ed942668690792db02b13"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "82e4617fb1d5a70843eaaa17da9b369bdfadc468"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@82e4617fb1d5a70843eaaa17da9b369bdfadc468",
"title": "82e4617fb1d5a70843eaaa17da9b369bdfadc468",
"url": "https://dart.googlesource.com/monorepo//+/82e4617fb1d5a70843eaaa17da9b369bdfadc468"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "eafe882c84ac7a3178f5e2b1061bd3f70faa61b1"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@eafe882c84ac7a3178f5e2b1061bd3f70faa61b1",
"title": "eafe882c84ac7a3178f5e2b1061bd3f70faa61b1",
"url": "https://dart.googlesource.com/monorepo//+/eafe882c84ac7a3178f5e2b1061bd3f70faa61b1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "3ec961fa1f03ed54836f193e6771a6ce46246c85"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@3ec961fa1f03ed54836f193e6771a6ce46246c85",
"title": "3ec961fa1f03ed54836f193e6771a6ce46246c85",
"url": "https://dart.googlesource.com/monorepo//+/3ec961fa1f03ed54836f193e6771a6ce46246c85"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "a07c1f354530c0e33ca2c61b53ed08d6c1a1e8f1"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@a07c1f354530c0e33ca2c61b53ed08d6c1a1e8f1",
"title": "a07c1f354530c0e33ca2c61b53ed08d6c1a1e8f1",
"url": "https://dart.googlesource.com/monorepo//+/a07c1f354530c0e33ca2c61b53ed08d6c1a1e8f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "e41225b51b90f989c299c407bbd8c367d9eef613"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@e41225b51b90f989c299c407bbd8c367d9eef613",
"title": "e41225b51b90f989c299c407bbd8c367d9eef613",
"url": "https://dart.googlesource.com/monorepo//+/e41225b51b90f989c299c407bbd8c367d9eef613"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "1ef528a1ac7cc06d1be9ff152f3c70705bb4d211"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@1ef528a1ac7cc06d1be9ff152f3c70705bb4d211",
"title": "1ef528a1ac7cc06d1be9ff152f3c70705bb4d211",
"url": "https://dart.googlesource.com/monorepo//+/1ef528a1ac7cc06d1be9ff152f3c70705bb4d211"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "4b64d336b589d9699e733ce16a13ff8227293483"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@4b64d336b589d9699e733ce16a13ff8227293483",
"title": "4b64d336b589d9699e733ce16a13ff8227293483",
"url": "https://dart.googlesource.com/monorepo//+/4b64d336b589d9699e733ce16a13ff8227293483"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://dart.googlesource.com/monorepo/",
"revision": "da5877629e422abc5235fefde05c8952e7748237"
},
"id": "https://dart.googlesource.com/monorepo//+/refs/heads/main@da5877629e422abc5235fefde05c8952e7748237",
"title": "da5877629e422abc5235fefde05c8952e7748237",
"url": "https://dart.googlesource.com/monorepo//+/da5877629e422abc5235fefde05c8952e7748237"
}
]
}
},
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "monorepo",
"id": "da5877629e422abc5235fefde05c8952e7748237",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8960190092824199712"
},
{
"key": "scheduler_job_id",
"value": "dart/flutter-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:24:26.383] Scheduled build:
{
"id": "8794760026414305249",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "flutter-linux"
},
"number": 86,
"createdBy": "project:dart",
"createTime": "2022-12-14T23:24:26.060415924Z",
"updateTime": "2022-12-14T23:24:26.060415924Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "monorepo",
"id": "da5877629e422abc5235fefde05c8952e7748237",
"ref": "refs/heads/main"
}
}
}
[23:24:26.383] Task URL: https://cr-buildbucket.appspot.com/build/8794760026414305249
[23:24:26.383] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:2:0) after 9m48s
[23:34:14.417] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:2:0)
[23:34:14.448] Build status: SCHEDULED
[23:34:14.448] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:3:0) after 9m41s
[23:43:55.765] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:3:0)
[23:43:55.833] Build status: SCHEDULED
[23:43:55.833] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:4:0) after 8m5s
[23:52:00.961] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:4:0)
[23:52:00.986] Build status: SCHEDULED
[23:52:00.986] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:5:0) after 1m36s
[23:53:37.186] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:5:0)
[23:53:37.581] Build status: SCHEDULED
[23:53:37.581] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:6:0) after 3m18s
[23:56:55.588] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:6:0)
[23:56:55.618] Build status: SCHEDULED
[23:56:55.618] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:7:0) after 2m12s
[23:59:07.772] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:7:0)
[23:59:07.801] Build status: SCHEDULED
[23:59:07.801] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:8:0) after 4m54s
[00:04:01.913] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:8:0)
[00:04:01.946] Build status: SCHEDULED
[00:04:01.946] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:9:0) after 9m9s
[00:13:11.413] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:9:0)
[00:13:11.452] Build status: SCHEDULED
[00:13:11.452] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:10:0) after 3m34s
[00:16:45.720] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:10:0)
[00:16:45.765] Build status: SCHEDULED
[00:16:45.765] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:11:0) after 9m11s
[00:25:56.926] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:11:0)
[00:25:56.957] Build status: SCHEDULED
[00:25:56.957] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:12:0) after 5m43s
[00:31:40.115] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:12:0)
[00:31:40.156] Build status: SCHEDULED
[00:31:40.156] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:13:0) after 5m35s
[00:37:15.371] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:13:0)
[00:37:15.409] Build status: SCHEDULED
[00:37:15.409] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:14:0) after 4m38s
[00:41:53.447] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:14:0)
[00:41:53.472] Build status: SCHEDULED
[00:41:53.472] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:15:0) after 8m25s
[00:50:18.491] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:15:0)
[00:50:18.520] Build status: SCHEDULED
[00:50:18.520] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:16:0) after 9m29s
[00:59:47.541] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:16:0)
[00:59:47.574] Build status: SCHEDULED
[00:59:47.574] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:17:0) after 7m57s
[01:07:44.634] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:17:0)
[01:07:44.776] Build status: SCHEDULED
[01:07:44.776] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:18:0) after 1m40s
[01:09:24.803] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:18:0)
[01:09:24.914] Build status: SCHEDULED
[01:09:24.914] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:19:0) after 1m22s
[01:10:47.023] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:19:0)
[01:10:47.049] Build status: SCHEDULED
[01:10:47.049] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:20:0) after 9m40s
[01:20:27.076] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:20:0)
[01:20:27.107] Build status: SCHEDULED
[01:20:27.107] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:21:0) after 4m48s
[01:25:15.157] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:21:0)
[01:25:15.186] Build status: SCHEDULED
[01:25:15.186] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:22:0) after 9m37s
[01:34:52.265] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:22:0)
[01:34:52.292] Build status: SCHEDULED
[01:34:52.292] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:23:0) after 9m40s
[01:44:32.310] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:23:0)
[01:44:32.329] Build status: SCHEDULED
[01:44:32.329] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:24:0) after 3m37s
[01:48:09.350] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:24:0)
[01:48:09.424] Build status: SCHEDULED
[01:48:09.424] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:25:0) after 7m35s
[01:48:38.633] Received PubSub notification, asking Buildbucket for the build status
[01:48:38.652] Build status: STARTED
[01:55:44.455] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:25:0)
[01:55:44.510] Build status: STARTED
[01:55:44.510] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:27:0) after 8m56s
[02:04:40.532] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:27:0)
[02:04:40.558] Build status: STARTED
[02:04:40.558] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:28:0) after 2m1s
[02:06:41.586] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:28:0)
[02:06:41.647] Build status: STARTED
[02:06:41.647] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:29:0) after 8m40s
[02:15:22.111] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:29:0)
[02:15:22.136] Build status: STARTED
[02:15:22.136] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:30:0) after 5m16s
[02:20:38.154] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:30:0)
[02:20:38.246] Build status: STARTED
[02:20:38.246] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:31:0) after 8m28s
[02:29:06.322] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:31:0)
[02:29:06.347] Build status: STARTED
[02:29:06.347] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:32:0) after 9m33s
[02:38:39.850] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:32:0)
[02:38:39.956] Build status: STARTED
[02:38:39.956] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:33:0) after 7m55s
[02:46:34.976] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:33:0)
[02:46:35.007] Build status: STARTED
[02:46:35.007] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:34:0) after 3m37s
[02:50:12.169] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:34:0)
[02:50:12.191] Build status: STARTED
[02:50:12.191] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:35:0) after 7m56s
[02:58:08.340] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:35:0)
[02:58:08.417] Build status: STARTED
[02:58:08.417] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:36:0) after 3m52s
[03:02:00.635] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:36:0)
[03:02:00.660] Build status: STARTED
[03:02:00.660] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:37:0) after 4m24s
[03:06:24.690] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:37:0)
[03:06:24.748] Build status: STARTED
[03:06:24.748] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:38:0) after 1m4s
[03:07:28.765] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:38:0)
[03:07:28.787] Build status: STARTED
[03:07:28.788] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:39:0) after 1m43s
[03:09:11.807] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:39:0)
[03:09:11.832] Build status: STARTED
[03:09:11.832] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:40:0) after 4m7s
[03:13:18.901] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:40:0)
[03:13:18.925] Build status: STARTED
[03:13:18.925] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:41:0) after 5m32s
[03:18:51.110] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:41:0)
[03:18:51.135] Build status: STARTED
[03:18:51.135] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:42:0) after 4m24s
[03:23:15.196] Handling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:42:0)
[03:23:15.264] Build status: STARTED
[03:23:15.264] Scheduling timer "check-buildbucket-build-status" (dart/flutter-linux:8960190092824199712:43:0) after 6m6s
[03:24:43.299] Received PubSub notification, asking Buildbucket for the build status
[03:24:43.325] Build:
{
"id": "8794760026414305249",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "flutter-linux"
},
"number": 86,
"createdBy": "project:dart",
"createTime": "2022-12-14T23:24:26.060415924Z",
"startTime": "2022-12-15T01:48:38.140009Z",
"endTime": "2022-12-15T03:24:41.335189254Z",
"updateTime": "2022-12-15T03:24:41.335189254Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "dart.googlesource.com",
"project": "monorepo",
"id": "da5877629e422abc5235fefde05c8952e7748237",
"ref": "refs/heads/main"
}
}
}
[03:24:43.325] Invocation finished in 4h0m22.761332353s with status SUCCEEDED