[18:59:05.005] New invocation is queued and will start shortly
[18:59:06.063] Starting the invocation (attempt 1)
[18:59:06.088] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/475d69c71bd622509d18a9e4ecf074081e40829d"
[18:59:06.088] Popped gitiles commit info from properties and tags
[18:59:06.088] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:59:06.089] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:59:06.090] Buildbucket request:
{
"requestId": "8889178759843919504",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889178759843919504",
"job": "chromium-m134/win32-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "094ef5e755b0087f94735ce7698a63de7048d55c"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@094ef5e755b0087f94735ce7698a63de7048d55c",
"title": "094ef5e755b0087f94735ce7698a63de7048d55c",
"url": "https://chromium.googlesource.com/chromium/src/+/094ef5e755b0087f94735ce7698a63de7048d55c"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "e8a90ce376874b2aa7f224445c97c0d9da83b573"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@e8a90ce376874b2aa7f224445c97c0d9da83b573",
"title": "e8a90ce376874b2aa7f224445c97c0d9da83b573",
"url": "https://chromium.googlesource.com/chromium/src/+/e8a90ce376874b2aa7f224445c97c0d9da83b573"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "208ce0182c556fda132922aa8ed3d0fed60261b7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@208ce0182c556fda132922aa8ed3d0fed60261b7",
"title": "208ce0182c556fda132922aa8ed3d0fed60261b7",
"url": "https://chromium.googlesource.com/chromium/src/+/208ce0182c556fda132922aa8ed3d0fed60261b7"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "b544bccbfa483cf66fbe92f5de4c45ce1b45ec7f"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@b544bccbfa483cf66fbe92f5de4c45ce1b45ec7f",
"title": "b544bccbfa483cf66fbe92f5de4c45ce1b45ec7f",
"url": "https://chromium.googlesource.com/chromium/src/+/b544bccbfa483cf66fbe92f5de4c45ce1b45ec7f"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "723fbed9819e4fa9c716ff3aa2d2174e5f6eda6b"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@723fbed9819e4fa9c716ff3aa2d2174e5f6eda6b",
"title": "723fbed9819e4fa9c716ff3aa2d2174e5f6eda6b",
"url": "https://chromium.googlesource.com/chromium/src/+/723fbed9819e4fa9c716ff3aa2d2174e5f6eda6b"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "9616137e85a9f394400c0bacdbb6f0294ab306a5"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@9616137e85a9f394400c0bacdbb6f0294ab306a5",
"title": "9616137e85a9f394400c0bacdbb6f0294ab306a5",
"url": "https://chromium.googlesource.com/chromium/src/+/9616137e85a9f394400c0bacdbb6f0294ab306a5"
},
{
"gitiles": {
"ref": "refs/branch-heads/6998",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "475d69c71bd622509d18a9e4ecf074081e40829d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/6998@475d69c71bd622509d18a9e4ecf074081e40829d",
"title": "475d69c71bd622509d18a9e4ecf074081e40829d",
"url": "https://chromium.googlesource.com/chromium/src/+/475d69c71bd622509d18a9e4ecf074081e40829d"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "475d69c71bd622509d18a9e4ecf074081e40829d",
"ref": "refs/branch-heads/6998"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889178759843919504"
},
{
"key": "scheduler_job_id",
"value": "chromium-m134/win32-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:59:06.639] Scheduled build:
{
"id": "8723748697990025153",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 9,
"createdBy": "project:chromium-m134",
"createTime": "2025-02-05T18:59:06.152856672Z",
"updateTime": "2025-02-05T18:59:06.152856672Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "475d69c71bd622509d18a9e4ecf074081e40829d",
"ref": "refs/branch-heads/6998"
}
}
}
[18:59:06.639] Task URL: https://cr-buildbucket.appspot.com/build/8723748697990025153
[18:59:06.639] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:2:0) after 7m53s
[18:59:08.527] Received PubSub notification, asking Buildbucket for the build status
[18:59:08.559] Build status: SCHEDULED
[18:59:22.398] Received PubSub notification, asking Buildbucket for the build status
[18:59:22.427] Build status: STARTED
[19:06:59.657] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:2:0)
[19:06:59.700] Build status: STARTED
[19:06:59.700] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:5:0) after 6m35s
[19:13:34.740] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:5:0)
[19:13:34.769] Build status: STARTED
[19:13:34.769] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:6:0) after 7m40s
[19:21:14.786] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:6:0)
[19:21:14.812] Build status: STARTED
[19:21:14.812] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:7:0) after 7m7s
[19:28:21.888] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:7:0)
[19:28:21.965] Build status: STARTED
[19:28:21.965] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:8:0) after 5m22s
[19:33:44.099] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:8:0)
[19:33:44.122] Build status: STARTED
[19:33:44.122] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:9:0) after 9m3s
[19:42:47.197] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:9:0)
[19:42:47.223] Build status: STARTED
[19:42:47.223] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:10:0) after 5m12s
[19:47:59.250] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:10:0)
[19:47:59.320] Build status: STARTED
[19:47:59.320] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:11:0) after 9m50s
[19:57:49.404] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:11:0)
[19:57:49.425] Build status: STARTED
[19:57:49.425] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:12:0) after 7m56s
[20:05:45.523] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:12:0)
[20:05:45.542] Build status: STARTED
[20:05:45.542] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:13:0) after 3m18s
[20:09:03.804] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:13:0)
[20:09:03.823] Build status: STARTED
[20:09:03.823] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:14:0) after 5m50s
[20:14:53.827] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:14:0)
[20:14:53.868] Build status: STARTED
[20:14:53.868] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:15:0) after 6m1s
[20:20:54.999] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:15:0)
[20:20:55.016] Build status: STARTED
[20:20:55.016] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:16:0) after 2m48s
[20:23:43.144] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:16:0)
[20:23:43.167] Build status: STARTED
[20:23:43.167] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:17:0) after 7m24s
[20:31:07.280] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:17:0)
[20:31:07.297] Build status: STARTED
[20:31:07.297] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:18:0) after 1m59s
[20:33:06.335] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:18:0)
[20:33:06.495] Build status: STARTED
[20:33:06.495] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:19:0) after 3m53s
[20:36:59.511] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:19:0)
[20:36:59.542] Build status: STARTED
[20:36:59.542] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:20:0) after 6m14s
[20:43:13.622] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:20:0)
[20:43:13.642] Build status: STARTED
[20:43:13.642] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:21:0) after 6m43s
[20:49:56.683] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:21:0)
[20:49:56.703] Build status: STARTED
[20:49:56.703] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:22:0) after 1m23s
[20:51:19.725] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:22:0)
[20:51:19.757] Build status: STARTED
[20:51:19.757] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:23:0) after 4m9s
[20:55:28.892] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:23:0)
[20:55:28.910] Build status: STARTED
[20:55:28.910] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:24:0) after 1m6s
[20:56:35.022] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:24:0)
[20:56:35.043] Build status: STARTED
[20:56:35.043] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:25:0) after 9m28s
[21:06:03.019] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:25:0)
[21:06:03.036] Build status: STARTED
[21:06:03.036] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:26:0) after 5m15s
[21:11:18.092] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:26:0)
[21:11:18.109] Build status: STARTED
[21:11:18.109] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:27:0) after 2m42s
[21:14:00.221] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:27:0)
[21:14:00.243] Build status: STARTED
[21:14:00.243] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:28:0) after 2m39s
[21:16:39.269] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:28:0)
[21:16:39.299] Build status: STARTED
[21:16:39.299] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:29:0) after 3m13s
[21:19:52.377] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:29:0)
[21:19:52.415] Build status: STARTED
[21:19:52.415] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:30:0) after 9m28s
[21:29:20.470] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:30:0)
[21:29:20.487] Build status: STARTED
[21:29:20.487] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:31:0) after 7m56s
[21:37:16.525] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:31:0)
[21:37:16.548] Build status: STARTED
[21:37:16.548] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:32:0) after 7m13s
[21:44:29.602] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:32:0)
[21:44:29.623] Build status: STARTED
[21:44:29.623] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:33:0) after 3m7s
[21:47:36.648] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:33:0)
[21:47:36.666] Build status: STARTED
[21:47:36.666] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:34:0) after 2m51s
[21:50:27.810] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:34:0)
[21:50:27.828] Build status: STARTED
[21:50:27.828] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:35:0) after 8m48s
[21:59:15.971] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:35:0)
[21:59:15.988] Build status: STARTED
[21:59:15.988] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:36:0) after 6m25s
[22:05:41.012] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:36:0)
[22:05:41.030] Build status: STARTED
[22:05:41.030] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:37:0) after 4m50s
[22:10:31.117] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:37:0)
[22:10:31.135] Build status: STARTED
[22:10:31.135] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:38:0) after 1m42s
[22:12:13.163] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:38:0)
[22:12:13.190] Build status: STARTED
[22:12:13.190] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:39:0) after 9m7s
[22:21:20.327] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:39:0)
[22:21:20.373] Build status: STARTED
[22:21:20.373] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:40:0) after 5m40s
[22:27:00.476] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:40:0)
[22:27:00.497] Build status: STARTED
[22:27:00.497] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:41:0) after 5m49s
[22:32:49.525] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:41:0)
[22:32:49.587] Build status: STARTED
[22:32:49.587] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:42:0) after 3m36s
[22:36:25.621] Handling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:42:0)
[22:36:25.641] Build status: STARTED
[22:36:25.641] Scheduling timer "check-buildbucket-build-status" (chromium-m134/win32-official:8889178759843919504:43:0) after 4m52s
[22:38:25.146] Received PubSub notification, asking Buildbucket for the build status
[22:38:25.164] Build:
{
"id": "8723748697990025153",
"builder": {
"project": "chromium-m134",
"bucket": "ci",
"builder": "win32-official"
},
"number": 9,
"createdBy": "project:chromium-m134",
"createTime": "2025-02-05T18:59:06.152856672Z",
"startTime": "2025-02-05T18:59:21.901908602Z",
"endTime": "2025-02-05T22:37:17.933566890Z",
"updateTime": "2025-02-05T22:37:17.933566890Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "475d69c71bd622509d18a9e4ecf074081e40829d",
"ref": "refs/branch-heads/6998"
}
}
}
[22:38:25.164] Invocation finished in 3h39m20.164417387s with status SUCCEEDED