[05:16:32.886] New invocation is queued and will start shortly
[05:16:33.949] Starting the invocation (attempt 1)
[05:16:33.982] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0570086b1b6437a74de6905f6572bc642015f85"
[05:16:33.982] Popped gitiles commit info from properties and tags
[05:16:33.982] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:16:33.982] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:16:33.985] Buildbucket request:
{
"requestId": "8895662893936329680",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8895662893936329680",
"job": "chromium-m130/linux-chromeos-dbg",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d35f2df3f6f9234bb6ded0c6a45332cfec267fa6"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@d35f2df3f6f9234bb6ded0c6a45332cfec267fa6",
"title": "d35f2df3f6f9234bb6ded0c6a45332cfec267fa6",
"url": "https://chromium.googlesource.com/chromium/src/+/d35f2df3f6f9234bb6ded0c6a45332cfec267fa6"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "ae5395006617ab1f7bce96c28825ecc1cbbed273"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@ae5395006617ab1f7bce96c28825ecc1cbbed273",
"title": "ae5395006617ab1f7bce96c28825ecc1cbbed273",
"url": "https://chromium.googlesource.com/chromium/src/+/ae5395006617ab1f7bce96c28825ecc1cbbed273"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "5faabee03851f63a119711ced4dda9641e86ea1d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@5faabee03851f63a119711ced4dda9641e86ea1d",
"title": "5faabee03851f63a119711ced4dda9641e86ea1d",
"url": "https://chromium.googlesource.com/chromium/src/+/5faabee03851f63a119711ced4dda9641e86ea1d"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1aae4510bc059165ad67567534a47fa5ffb1c9f1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@1aae4510bc059165ad67567534a47fa5ffb1c9f1",
"title": "1aae4510bc059165ad67567534a47fa5ffb1c9f1",
"url": "https://chromium.googlesource.com/chromium/src/+/1aae4510bc059165ad67567534a47fa5ffb1c9f1"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "7477b0999148f6c73deead6e01c4c5391af2d67b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@7477b0999148f6c73deead6e01c4c5391af2d67b",
"title": "7477b0999148f6c73deead6e01c4c5391af2d67b",
"url": "https://chromium.googlesource.com/chromium/src/+/7477b0999148f6c73deead6e01c4c5391af2d67b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "1229dd1e8cafcbfee52e91ce55c9b6ed437acb35"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@1229dd1e8cafcbfee52e91ce55c9b6ed437acb35",
"title": "1229dd1e8cafcbfee52e91ce55c9b6ed437acb35",
"url": "https://chromium.googlesource.com/chromium/src/+/1229dd1e8cafcbfee52e91ce55c9b6ed437acb35"
},
{
"gitiles": {
"ref": "refs/branch-heads/6723",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a0570086b1b6437a74de6905f6572bc642015f85"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6723@a0570086b1b6437a74de6905f6572bc642015f85",
"title": "a0570086b1b6437a74de6905f6572bc642015f85",
"url": "https://chromium.googlesource.com/chromium/src/+/a0570086b1b6437a74de6905f6572bc642015f85"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a0570086b1b6437a74de6905f6572bc642015f85",
"ref": "refs/branch-heads/6723"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895662893936329680"
},
{
"key": "scheduler_job_id",
"value": "chromium-m130/linux-chromeos-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:16:34.630] Scheduled build:
{
"id": "8730232832013267985",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 653,
"createdBy": "project:chromium-m130",
"createTime": "2024-11-26T05:16:34.070683817Z",
"updateTime": "2024-11-26T05:16:34.070683817Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a0570086b1b6437a74de6905f6572bc642015f85",
"ref": "refs/branch-heads/6723"
}
}
}
[05:16:34.630] Task URL: https://cr-buildbucket.appspot.com/build/8730232832013267985
[05:16:34.630] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:2:0) after 6m50s
[05:16:34.995] Received PubSub notification, asking Buildbucket for the build status
[05:16:35.018] Build status: SCHEDULED
[05:23:24.660] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:2:0)
[05:23:24.679] Build status: SCHEDULED
[05:23:24.679] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:4:0) after 8m27s
[05:25:53.572] Received PubSub notification, asking Buildbucket for the build status
[05:25:53.590] Build status: STARTED
[05:31:51.950] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:4:0)
[05:31:51.970] Build status: STARTED
[05:31:51.970] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:6:0) after 8m47s
[05:40:39.079] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:6:0)
[05:40:39.109] Build status: STARTED
[05:40:39.109] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:7:0) after 3m0s
[05:43:39.151] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:7:0)
[05:43:39.178] Build status: STARTED
[05:43:39.178] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:8:0) after 7m15s
[05:50:54.504] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:8:0)
[05:50:54.604] Build status: STARTED
[05:50:54.604] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:9:0) after 7m28s
[05:58:22.776] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:9:0)
[05:58:22.803] Build status: STARTED
[05:58:22.803] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:10:0) after 5m3s
[06:03:26.007] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:10:0)
[06:03:26.030] Build status: STARTED
[06:03:26.030] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:11:0) after 3m42s
[06:07:08.167] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:11:0)
[06:07:08.188] Build status: STARTED
[06:07:08.188] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:12:0) after 6m37s
[06:13:45.239] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:12:0)
[06:13:45.259] Build status: STARTED
[06:13:45.259] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:13:0) after 8m34s
[06:22:19.377] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:13:0)
[06:22:19.393] Build status: STARTED
[06:22:19.393] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:14:0) after 2m59s
[06:25:18.470] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:14:0)
[06:25:18.501] Build status: STARTED
[06:25:18.501] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:15:0) after 6m30s
[06:31:48.524] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:15:0)
[06:31:48.546] Build status: STARTED
[06:31:48.546] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:16:0) after 2m48s
[06:34:36.654] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:16:0)
[06:34:36.679] Build status: STARTED
[06:34:36.679] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:17:0) after 4m40s
[06:39:17.257] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:17:0)
[06:39:17.334] Build status: STARTED
[06:39:17.334] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:18:0) after 5m40s
[06:44:57.348] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:18:0)
[06:44:57.366] Build status: STARTED
[06:44:57.366] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:19:0) after 4m56s
[06:49:53.514] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:19:0)
[06:49:53.532] Build status: STARTED
[06:49:53.532] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:20:0) after 4m0s
[06:53:53.579] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:20:0)
[06:53:53.595] Build status: STARTED
[06:53:53.595] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:21:0) after 9m43s
[07:03:36.611] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:21:0)
[07:03:36.636] Build status: STARTED
[07:03:36.636] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:22:0) after 1m41s
[07:05:17.653] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:22:0)
[07:05:17.683] Build status: STARTED
[07:05:17.683] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:23:0) after 9m36s
[07:14:53.913] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:23:0)
[07:14:54.065] Build status: STARTED
[07:14:54.065] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:24:0) after 8m52s
[07:23:46.064] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:24:0)
[07:23:46.085] Build status: STARTED
[07:23:46.085] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:25:0) after 2m56s
[07:26:42.290] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:25:0)
[07:26:42.309] Build status: STARTED
[07:26:42.309] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:26:0) after 3m42s
[07:30:24.642] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:26:0)
[07:30:24.669] Build status: STARTED
[07:30:24.669] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:27:0) after 8m34s
[07:38:59.032] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:27:0)
[07:38:59.052] Build status: STARTED
[07:38:59.052] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:28:0) after 1m31s
[07:40:30.171] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:28:0)
[07:40:30.190] Build status: STARTED
[07:40:30.190] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:29:0) after 4m37s
[07:45:07.365] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:29:0)
[07:45:07.387] Build status: STARTED
[07:45:07.387] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:30:0) after 2m50s
[07:47:57.402] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:30:0)
[07:47:57.426] Build status: STARTED
[07:47:57.426] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:31:0) after 2m28s
[07:50:25.433] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:31:0)
[07:50:25.452] Build status: STARTED
[07:50:25.452] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:32:0) after 7m44s
[07:58:09.467] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:32:0)
[07:58:09.483] Build status: STARTED
[07:58:09.483] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:33:0) after 1m7s
[07:59:16.504] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:33:0)
[07:59:16.526] Build status: STARTED
[07:59:16.526] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:34:0) after 8m43s
[08:07:59.542] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:34:0)
[08:07:59.617] Build status: STARTED
[08:07:59.617] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:35:0) after 9m48s
[08:17:47.667] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:35:0)
[08:17:47.689] Build status: STARTED
[08:17:47.689] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:36:0) after 6m30s
[08:24:17.761] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:36:0)
[08:24:17.791] Build status: STARTED
[08:24:17.791] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:37:0) after 3m0s
[08:27:17.806] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:37:0)
[08:27:17.853] Build status: STARTED
[08:27:17.853] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:38:0) after 3m48s
[08:31:06.196] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:38:0)
[08:31:06.252] Build status: STARTED
[08:31:06.252] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:39:0) after 6m1s
[08:37:07.371] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:39:0)
[08:37:07.390] Build status: STARTED
[08:37:07.390] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:40:0) after 9m28s
[08:46:35.406] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:40:0)
[08:46:35.422] Build status: STARTED
[08:46:35.422] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:41:0) after 3m53s
[08:50:28.441] Handling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:41:0)
[08:50:28.458] Build status: STARTED
[08:50:28.458] Scheduling timer "check-buildbucket-build-status" (chromium-m130/linux-chromeos-dbg:8895662893936329680:42:0) after 6m0s
[08:53:46.329] Received PubSub notification, asking Buildbucket for the build status
[08:53:46.429] Build:
{
"id": "8730232832013267985",
"builder": {
"project": "chromium-m130",
"bucket": "ci",
"builder": "linux-chromeos-dbg"
},
"number": 653,
"createdBy": "project:chromium-m130",
"createTime": "2024-11-26T05:16:34.070683817Z",
"startTime": "2024-11-26T05:25:53.400737940Z",
"endTime": "2024-11-26T08:53:45.816651092Z",
"updateTime": "2024-11-26T08:53:45.816651092Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a0570086b1b6437a74de6905f6572bc642015f85",
"ref": "refs/branch-heads/6723"
}
}
}
[08:53:46.429] Invocation finished in 3h37m13.577215162s with status SUCCEEDED