[18:20:12.658] New invocation is queued and will start shortly
[18:20:13.872] Starting the invocation (attempt 1)
[18:20:13.902] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcc9303e916cdad038e497be632f48cf1db8b9e5"
[18:20:13.902] Popped gitiles commit info from properties and tags
[18:20:13.902] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:20:13.902] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:20:13.903] Buildbucket request:
{
"requestId": "8873507930312125376",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873507930312125376",
"job": "chromium-m138/mac-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "2d64ccbb0716a9c780633f2f193d3cef31637892"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@2d64ccbb0716a9c780633f2f193d3cef31637892",
"title": "2d64ccbb0716a9c780633f2f193d3cef31637892",
"url": "https://chromium.googlesource.com/chromium/src/+/2d64ccbb0716a9c780633f2f193d3cef31637892"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "652997ca80b53948d218ce7c48bf60fd0ad27be2"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@652997ca80b53948d218ce7c48bf60fd0ad27be2",
"title": "652997ca80b53948d218ce7c48bf60fd0ad27be2",
"url": "https://chromium.googlesource.com/chromium/src/+/652997ca80b53948d218ce7c48bf60fd0ad27be2"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "497647dca1a8ab934023238b4e05e9fadbdd8d7c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@497647dca1a8ab934023238b4e05e9fadbdd8d7c",
"title": "497647dca1a8ab934023238b4e05e9fadbdd8d7c",
"url": "https://chromium.googlesource.com/chromium/src/+/497647dca1a8ab934023238b4e05e9fadbdd8d7c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb39029c22e42d13295135770d137441afaacd93"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@bb39029c22e42d13295135770d137441afaacd93",
"title": "bb39029c22e42d13295135770d137441afaacd93",
"url": "https://chromium.googlesource.com/chromium/src/+/bb39029c22e42d13295135770d137441afaacd93"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "578ce96e98928229d4bd9f323b538c0dc142cf62"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@578ce96e98928229d4bd9f323b538c0dc142cf62",
"title": "578ce96e98928229d4bd9f323b538c0dc142cf62",
"url": "https://chromium.googlesource.com/chromium/src/+/578ce96e98928229d4bd9f323b538c0dc142cf62"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0fcea64e4719765673ff0dd14da4f93e1d9bab44"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@0fcea64e4719765673ff0dd14da4f93e1d9bab44",
"title": "0fcea64e4719765673ff0dd14da4f93e1d9bab44",
"url": "https://chromium.googlesource.com/chromium/src/+/0fcea64e4719765673ff0dd14da4f93e1d9bab44"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8d7aeec17bdac07365aaccf0047e5bfd2c420963"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@8d7aeec17bdac07365aaccf0047e5bfd2c420963",
"title": "8d7aeec17bdac07365aaccf0047e5bfd2c420963",
"url": "https://chromium.googlesource.com/chromium/src/+/8d7aeec17bdac07365aaccf0047e5bfd2c420963"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "029832e44a8a8f17d4bf6ee025e71ce81bea1317"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@029832e44a8a8f17d4bf6ee025e71ce81bea1317",
"title": "029832e44a8a8f17d4bf6ee025e71ce81bea1317",
"url": "https://chromium.googlesource.com/chromium/src/+/029832e44a8a8f17d4bf6ee025e71ce81bea1317"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "806c96a0837056af5e93986558159277ef9bba0b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@806c96a0837056af5e93986558159277ef9bba0b",
"title": "806c96a0837056af5e93986558159277ef9bba0b",
"url": "https://chromium.googlesource.com/chromium/src/+/806c96a0837056af5e93986558159277ef9bba0b"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "3d6bab697bd3dcb318d30ea6481139bef91a012c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@3d6bab697bd3dcb318d30ea6481139bef91a012c",
"title": "3d6bab697bd3dcb318d30ea6481139bef91a012c",
"url": "https://chromium.googlesource.com/chromium/src/+/3d6bab697bd3dcb318d30ea6481139bef91a012c"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "4aa96a930a0312764635778a86222358697d8765"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@4aa96a930a0312764635778a86222358697d8765",
"title": "4aa96a930a0312764635778a86222358697d8765",
"url": "https://chromium.googlesource.com/chromium/src/+/4aa96a930a0312764635778a86222358697d8765"
},
{
"gitiles": {
"ref": "refs/branch-heads/7204",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "dcc9303e916cdad038e497be632f48cf1db8b9e5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7204@dcc9303e916cdad038e497be632f48cf1db8b9e5",
"title": "dcc9303e916cdad038e497be632f48cf1db8b9e5",
"url": "https://chromium.googlesource.com/chromium/src/+/dcc9303e916cdad038e497be632f48cf1db8b9e5"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dcc9303e916cdad038e497be632f48cf1db8b9e5",
"ref": "refs/branch-heads/7204"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873507930312125376"
},
{
"key": "scheduler_job_id",
"value": "chromium-m138/mac-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:20:14.476] Scheduled build:
{
"id": "8708077868265834737",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 444,
"createdBy": "project:chromium-m138",
"createTime": "2025-07-28T18:20:13.977440412Z",
"updateTime": "2025-07-28T18:20:13.977440412Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dcc9303e916cdad038e497be632f48cf1db8b9e5",
"ref": "refs/branch-heads/7204"
}
}
}
[18:20:14.476] Task URL: https://cr-buildbucket.appspot.com/build/8708077868265834737
[18:20:14.476] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:2:0) after 2m58s
[18:20:15.619] Received PubSub notification, asking Buildbucket for the build status
[18:20:15.655] Build status: SCHEDULED
[18:20:18.008] Received PubSub notification, asking Buildbucket for the build status
[18:20:18.053] Build status: STARTED
[18:23:12.517] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:2:0)
[18:23:12.544] Build status: STARTED
[18:23:12.544] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:5:0) after 5m12s
[18:28:24.565] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:5:0)
[18:28:24.596] Build status: STARTED
[18:28:24.596] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:6:0) after 4m7s
[18:32:31.786] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:6:0)
[18:32:31.811] Build status: STARTED
[18:32:31.811] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:7:0) after 6m48s
[18:39:20.019] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:7:0)
[18:39:20.041] Build status: STARTED
[18:39:20.041] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:8:0) after 4m38s
[18:43:58.061] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:8:0)
[18:43:58.091] Build status: STARTED
[18:43:58.092] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:9:0) after 5m30s
[18:49:28.117] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:9:0)
[18:49:28.146] Build status: STARTED
[18:49:28.146] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:10:0) after 1m41s
[18:51:09.154] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:10:0)
[18:51:09.204] Build status: STARTED
[18:51:09.205] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:11:0) after 6m23s
[18:57:32.391] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:11:0)
[18:57:32.410] Build status: STARTED
[18:57:32.410] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:12:0) after 3m39s
[19:01:11.467] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:12:0)
[19:01:11.486] Build status: STARTED
[19:01:11.486] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:13:0) after 2m6s
[19:03:17.517] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:13:0)
[19:03:17.534] Build status: STARTED
[19:03:17.535] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:14:0) after 4m53s
[19:08:10.556] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:14:0)
[19:08:10.576] Build status: STARTED
[19:08:10.576] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:15:0) after 7m25s
[19:15:35.689] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:15:0)
[19:15:35.709] Build status: STARTED
[19:15:35.709] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:16:0) after 4m10s
[19:19:45.984] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:16:0)
[19:19:46.002] Build status: STARTED
[19:19:46.002] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:17:0) after 6m36s
[19:26:22.111] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:17:0)
[19:26:22.132] Build status: STARTED
[19:26:22.132] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:18:0) after 7m49s
[19:34:11.283] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:18:0)
[19:34:11.337] Build status: STARTED
[19:34:11.337] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:19:0) after 1m14s
[19:35:25.501] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:19:0)
[19:35:25.553] Build status: STARTED
[19:35:25.553] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:20:0) after 5m33s
[19:40:58.575] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:20:0)
[19:40:58.598] Build status: STARTED
[19:40:58.598] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:21:0) after 5m45s
[19:46:43.843] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:21:0)
[19:46:43.862] Build status: STARTED
[19:46:43.862] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:22:0) after 8m41s
[19:55:24.908] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:22:0)
[19:55:24.929] Build status: STARTED
[19:55:24.929] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:23:0) after 3m4s
[19:58:28.953] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:23:0)
[19:58:28.971] Build status: STARTED
[19:58:28.971] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:24:0) after 7m50s
[20:06:18.991] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:24:0)
[20:06:19.014] Build status: STARTED
[20:06:19.014] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:25:0) after 5m16s
[20:11:35.089] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:25:0)
[20:11:35.103] Build status: STARTED
[20:11:35.103] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:26:0) after 1m9s
[20:12:44.077] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:26:0)
[20:12:44.123] Build status: STARTED
[20:12:44.123] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:27:0) after 7m41s
[20:20:25.180] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:27:0)
[20:20:25.204] Build status: STARTED
[20:20:25.204] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:28:0) after 9m51s
[20:30:16.396] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:28:0)
[20:30:16.435] Build status: STARTED
[20:30:16.435] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:29:0) after 8m38s
[20:38:54.455] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:29:0)
[20:38:54.472] Build status: STARTED
[20:38:54.472] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:30:0) after 6m19s
[20:45:13.488] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:30:0)
[20:45:13.506] Build status: STARTED
[20:45:13.506] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:31:0) after 5m44s
[20:50:57.659] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:31:0)
[20:50:57.678] Build status: STARTED
[20:50:57.678] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:32:0) after 2m36s
[20:53:33.782] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:32:0)
[20:53:33.799] Build status: STARTED
[20:53:33.799] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:33:0) after 4m31s
[20:58:04.869] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:33:0)
[20:58:04.889] Build status: STARTED
[20:58:04.889] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:34:0) after 4m21s
[21:02:26.240] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:34:0)
[21:02:26.272] Build status: STARTED
[21:02:26.272] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:35:0) after 2m54s
[21:05:20.293] Handling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:35:0)
[21:05:20.319] Build status: STARTED
[21:05:20.319] Scheduling timer "check-buildbucket-build-status" (chromium-m138/mac-official:8873507930312125376:36:0) after 9m36s
[21:11:06.997] Received PubSub notification, asking Buildbucket for the build status
[21:11:07.019] Build:
{
"id": "8708077868265834737",
"builder": {
"project": "chromium-m138",
"bucket": "ci",
"builder": "mac-official"
},
"number": 444,
"createdBy": "project:chromium-m138",
"createTime": "2025-07-28T18:20:13.977440412Z",
"startTime": "2025-07-28T18:20:17.696427226Z",
"endTime": "2025-07-28T21:11:06.675816836Z",
"updateTime": "2025-07-28T21:11:06.675816836Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "dcc9303e916cdad038e497be632f48cf1db8b9e5",
"ref": "refs/branch-heads/7204"
}
}
}
[21:11:07.019] Invocation finished in 2h50m54.376363613s with status SUCCEEDED