[06:05:40.411] New invocation is queued and will start shortly
[06:05:41.537] Starting the invocation (attempt 1)
[06:05:41.564] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b24864d001a00f7405200a87c956ed19ee91e443"
[06:05:41.564] Popped gitiles commit info from properties and tags
[06:05:41.564] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:05:41.564] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:05:41.565] Buildbucket request:
{
"requestId": "8884153988473996560",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8884153988473996560",
"job": "chromium-m134/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f29c76b6d50347fce6afeea5de9a489ee57b0b2d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@f29c76b6d50347fce6afeea5de9a489ee57b0b2d",
"title": "f29c76b6d50347fce6afeea5de9a489ee57b0b2d",
"url": "https://chromium.googlesource.com/chromium/src/+/f29c76b6d50347fce6afeea5de9a489ee57b0b2d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ef4cddda8209b4f3f10df8dcc3d2aa1da45dd146"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@ef4cddda8209b4f3f10df8dcc3d2aa1da45dd146",
"title": "ef4cddda8209b4f3f10df8dcc3d2aa1da45dd146",
"url": "https://chromium.googlesource.com/chromium/src/+/ef4cddda8209b4f3f10df8dcc3d2aa1da45dd146"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "57e1ef1b55ea6963a56b6a217d8bf137ed8b1150"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@57e1ef1b55ea6963a56b6a217d8bf137ed8b1150",
"title": "57e1ef1b55ea6963a56b6a217d8bf137ed8b1150",
"url": "https://chromium.googlesource.com/chromium/src/+/57e1ef1b55ea6963a56b6a217d8bf137ed8b1150"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7dcdc87be4ddcbd57a38c42124455d65e7378480"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@7dcdc87be4ddcbd57a38c42124455d65e7378480",
"title": "7dcdc87be4ddcbd57a38c42124455d65e7378480",
"url": "https://chromium.googlesource.com/chromium/src/+/7dcdc87be4ddcbd57a38c42124455d65e7378480"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "68998d482c111276c0cb895edca26ca57a3db1b3"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@68998d482c111276c0cb895edca26ca57a3db1b3",
"title": "68998d482c111276c0cb895edca26ca57a3db1b3",
"url": "https://chromium.googlesource.com/chromium/src/+/68998d482c111276c0cb895edca26ca57a3db1b3"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "f36d47e198c5cc0c3ce99e4f885873fdd6929e4d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@f36d47e198c5cc0c3ce99e4f885873fdd6929e4d",
"title": "f36d47e198c5cc0c3ce99e4f885873fdd6929e4d",
"url": "https://chromium.googlesource.com/chromium/src/+/f36d47e198c5cc0c3ce99e4f885873fdd6929e4d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b24864d001a00f7405200a87c956ed19ee91e443"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@b24864d001a00f7405200a87c956ed19ee91e443",
"title": "b24864d001a00f7405200a87c956ed19ee91e443",
"url": "https://chromium.googlesource.com/chromium/src/+/b24864d001a00f7405200a87c956ed19ee91e443"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b24864d001a00f7405200a87c956ed19ee91e443",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8884153988473996560"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:05:42.047] Scheduled build:
{
"id": "8718723926548572209",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "mac-official"
},
"number": 419,
"createdBy": "project:chromium-m134",
"createTime": "2025-04-02T06:05:41.621837571Z",
"updateTime": "2025-04-02T06:05:41.621837571Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b24864d001a00f7405200a87c956ed19ee91e443",
"ref": "refs/branch-heads/6998"
}
},
"canary": true
}
[06:05:42.047] Task URL: https://cr-buildbucket.appspot.com/build/8718723926548572209
[06:05:42.047] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:2:0) after 9m8s
[06:05:43.046] Received PubSub notification, asking Buildbucket for the build status
[06:05:43.069] Build status: SCHEDULED
[06:12:52.011] Received PubSub notification, asking Buildbucket for the build status
[06:12:52.037] Build status: STARTED
[06:14:50.070] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:2:0)
[06:14:50.093] Build status: STARTED
[06:14:50.093] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:5:0) after 5m33s
[06:20:23.125] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:5:0)
[06:20:23.158] Build status: STARTED
[06:20:23.158] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:6:0) after 5m32s
[06:25:55.265] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:6:0)
[06:25:55.290] Build status: STARTED
[06:25:55.290] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:7:0) after 2m49s
[06:28:44.312] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:7:0)
[06:28:44.362] Build status: STARTED
[06:28:44.362] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:8:0) after 4m44s
[06:33:28.381] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:8:0)
[06:33:28.408] Build status: STARTED
[06:33:28.408] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:9:0) after 7m56s
[06:41:24.555] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:9:0)
[06:41:24.584] Build status: STARTED
[06:41:24.584] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:10:0) after 4m59s
[06:46:23.597] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:10:0)
[06:46:23.617] Build status: STARTED
[06:46:23.617] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:11:0) after 9m20s
[06:55:43.907] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:11:0)
[06:55:43.934] Build status: STARTED
[06:55:43.934] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:12:0) after 4m17s
[07:00:01.104] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:12:0)
[07:00:01.130] Build status: STARTED
[07:00:01.130] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:13:0) after 6m9s
[07:06:10.204] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:13:0)
[07:06:10.252] Build status: STARTED
[07:06:10.252] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:14:0) after 1m7s
[07:07:17.474] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:14:0)
[07:07:17.496] Build status: STARTED
[07:07:17.496] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:15:0) after 6m19s
[07:13:36.518] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:15:0)
[07:13:36.544] Build status: STARTED
[07:13:36.544] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:16:0) after 3m5s
[07:16:41.562] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:16:0)
[07:16:41.595] Build status: STARTED
[07:16:41.595] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:17:0) after 4m41s
[07:21:22.617] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:17:0)
[07:21:22.644] Build status: STARTED
[07:21:22.644] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:18:0) after 5m54s
[07:27:16.663] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:18:0)
[07:27:16.678] Build status: STARTED
[07:27:16.678] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:19:0) after 3m11s
[07:30:27.736] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:19:0)
[07:30:27.760] Build status: STARTED
[07:30:27.760] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:20:0) after 2m24s
[07:32:52.066] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:20:0)
[07:32:52.099] Build status: STARTED
[07:32:52.099] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:21:0) after 4m4s
[07:36:56.168] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:21:0)
[07:36:56.187] Build status: STARTED
[07:36:56.187] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:22:0) after 5m21s
[07:42:17.310] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:22:0)
[07:42:17.346] Build status: STARTED
[07:42:17.347] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:23:0) after 1m3s
[07:43:20.366] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:23:0)
[07:43:20.392] Build status: STARTED
[07:43:20.392] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:24:0) after 6m39s
[07:49:59.692] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:24:0)
[07:49:59.714] Build status: STARTED
[07:49:59.714] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:25:0) after 7m3s
[07:57:02.934] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:25:0)
[07:57:02.953] Build status: STARTED
[07:57:02.953] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:26:0) after 1m40s
[07:58:43.193] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:26:0)
[07:58:43.263] Build status: STARTED
[07:58:43.263] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:27:0) after 4m13s
[08:02:56.285] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:27:0)
[08:02:56.356] Build status: STARTED
[08:02:56.356] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:28:0) after 5m15s
[08:08:11.375] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:28:0)
[08:08:11.398] Build status: STARTED
[08:08:11.398] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:29:0) after 9m28s
[08:17:39.462] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:29:0)
[08:17:39.486] Build status: STARTED
[08:17:39.486] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:30:0) after 1m38s
[08:19:17.502] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:30:0)
[08:19:17.580] Build status: STARTED
[08:19:17.580] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:31:0) after 9m2s
[08:28:19.582] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:31:0)
[08:28:19.660] Build status: STARTED
[08:28:19.660] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:32:0) after 9m14s
[08:37:33.696] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:32:0)
[08:37:33.716] Build status: STARTED
[08:37:33.716] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:33:0) after 5m16s
[08:42:49.782] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:33:0)
[08:42:49.808] Build status: STARTED
[08:42:49.808] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:34:0) after 4m39s
[08:47:28.822] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:34:0)
[08:47:28.904] Build status: STARTED
[08:47:28.904] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:35:0) after 4m17s
[08:51:45.922] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:35:0)
[08:51:45.947] Build status: STARTED
[08:51:45.947] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:36:0) after 3m13s
[08:54:58.991] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:36:0)
[08:54:59.034] Build status: STARTED
[08:54:59.034] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:37:0) after 5m3s
[09:00:02.058] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:37:0)
[09:00:02.088] Build status: STARTED
[09:00:02.088] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:38:0) after 4m26s
[09:04:28.085] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:38:0)
[09:04:28.112] Build status: STARTED
[09:04:28.112] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:39:0) after 4m32s
[09:09:00.652] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:39:0)
[09:09:00.713] Build status: STARTED
[09:09:00.713] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:40:0) after 5m33s
[09:14:33.852] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:40:0)
[09:14:33.876] Build status: STARTED
[09:14:33.876] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:41:0) after 8m18s
[09:22:51.973] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:41:0)
[09:22:52.039] Build status: STARTED
[09:22:52.039] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:42:0) after 1m30s
[09:24:22.079] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:42:0)
[09:24:22.139] Build status: STARTED
[09:24:22.139] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:43:0) after 7m21s
[09:31:43.281] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:43:0)
[09:31:43.299] Build status: STARTED
[09:31:43.299] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:44:0) after 3m7s
[09:34:50.318] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:44:0)
[09:34:50.407] Build status: STARTED
[09:34:50.407] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:45:0) after 2m30s
[09:37:20.444] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:45:0)
[09:37:20.461] Build status: STARTED
[09:37:20.461] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:46:0) after 3m21s
[09:40:41.535] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:46:0)
[09:40:41.562] Build status: STARTED
[09:40:41.562] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:47:0) after 8m27s
[09:49:08.684] Handling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:47:0)
[09:49:08.709] Build status: STARTED
[09:49:08.709] Scheduling timer "check-buildbucket-build-status" (chromium-m134/mac-official:8884153988473996560:48:0) after 6m37s
[09:49:30.263] Received PubSub notification, asking Buildbucket for the build status
[09:49:30.277] Build:
{
"id": "8718723926548572209",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "mac-official"
},
"number": 419,
"createdBy": "project:chromium-m134",
"createTime": "2025-04-02T06:05:41.621837571Z",
"startTime": "2025-04-02T06:12:51.725062651Z",
"endTime": "2025-04-02T09:49:30.007549337Z",
"updateTime": "2025-04-02T09:49:30.007549337Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b24864d001a00f7405200a87c956ed19ee91e443",
"ref": "refs/branch-heads/6998"
}
},
"canary": true
}
[09:49:30.277] Invocation finished in 3h43m49.87530538s with status SUCCEEDED