[00:43:24.718] New invocation is queued and will start shortly
[00:43:25.809] Starting the invocation (attempt 1)
[00:43:25.833] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d473df1ea84faa28891aacf293cd04a9f381e5de"
[00:43:25.833] Popped gitiles commit info from properties and tags
[00:43:25.833] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:43:25.833] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:43:25.834] Buildbucket request:
{
"requestId": "8870312927551718896",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870312927551718896",
"job": "chromium-m140/win-official",
"triggers": [
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "530c3279591b6489e4375e2b7c034e5771c61710"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@530c3279591b6489e4375e2b7c034e5771c61710",
"title": "530c3279591b6489e4375e2b7c034e5771c61710",
"url": "https://chromium.googlesource.com/chromium/src/+/530c3279591b6489e4375e2b7c034e5771c61710"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "8e06a3b3f5b711dbb1d0107314b9d1e77c3813a7"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@8e06a3b3f5b711dbb1d0107314b9d1e77c3813a7",
"title": "8e06a3b3f5b711dbb1d0107314b9d1e77c3813a7",
"url": "https://chromium.googlesource.com/chromium/src/+/8e06a3b3f5b711dbb1d0107314b9d1e77c3813a7"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "bb8551bfc38201c3a286d15dd92cc1bb6789f48d"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@bb8551bfc38201c3a286d15dd92cc1bb6789f48d",
"title": "bb8551bfc38201c3a286d15dd92cc1bb6789f48d",
"url": "https://chromium.googlesource.com/chromium/src/+/bb8551bfc38201c3a286d15dd92cc1bb6789f48d"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a20a23aaa29fca29c07b3c3f280b6511bbb19ff1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@a20a23aaa29fca29c07b3c3f280b6511bbb19ff1",
"title": "a20a23aaa29fca29c07b3c3f280b6511bbb19ff1",
"url": "https://chromium.googlesource.com/chromium/src/+/a20a23aaa29fca29c07b3c3f280b6511bbb19ff1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "a247a21ee792d94848af74b7b43177cb171f4d59"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@a247a21ee792d94848af74b7b43177cb171f4d59",
"title": "a247a21ee792d94848af74b7b43177cb171f4d59",
"url": "https://chromium.googlesource.com/chromium/src/+/a247a21ee792d94848af74b7b43177cb171f4d59"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d44fcaf94dac96aec4811e83f048872e179be370"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@d44fcaf94dac96aec4811e83f048872e179be370",
"title": "d44fcaf94dac96aec4811e83f048872e179be370",
"url": "https://chromium.googlesource.com/chromium/src/+/d44fcaf94dac96aec4811e83f048872e179be370"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "0999188cf8843b6b8248f8b42d369865085e74a1"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@0999188cf8843b6b8248f8b42d369865085e74a1",
"title": "0999188cf8843b6b8248f8b42d369865085e74a1",
"url": "https://chromium.googlesource.com/chromium/src/+/0999188cf8843b6b8248f8b42d369865085e74a1"
},
{
"gitiles": {
"ref": "refs/branch-heads/7339",
"repo": "https://chromium.googlesource.com/chromium/src",
"revision": "d473df1ea84faa28891aacf293cd04a9f381e5de"
},
"id": "https://chromium.googlesource.com/chromium/src/+/refs/branch-heads/7339@d473df1ea84faa28891aacf293cd04a9f381e5de",
"title": "d473df1ea84faa28891aacf293cd04a9f381e5de",
"url": "https://chromium.googlesource.com/chromium/src/+/d473df1ea84faa28891aacf293cd04a9f381e5de"
}
]
}
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d473df1ea84faa28891aacf293cd04a9f381e5de",
"ref": "refs/branch-heads/7339"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870312927551718896"
},
{
"key": "scheduler_job_id",
"value": "chromium-m140/win-official"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:43:26.312] Scheduled build:
{
"id": "8704882865679994241",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"number": 236,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-02T00:43:25.884880552Z",
"updateTime": "2025-09-02T00:43:25.884880552Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d473df1ea84faa28891aacf293cd04a9f381e5de",
"ref": "refs/branch-heads/7339"
}
}
}
[00:43:26.312] Task URL: https://cr-buildbucket.appspot.com/build/8704882865679994241
[00:43:26.312] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:2:0) after 2m11s
[00:43:36.586] Received PubSub notification, asking Buildbucket for the build status
[00:43:36.615] Build status: STARTED
[00:43:42.020] Received PubSub notification, asking Buildbucket for the build status
[00:43:42.036] Build status: STARTED
[00:45:37.688] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:2:0)
[00:45:37.711] Build status: STARTED
[00:45:37.711] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:5:0) after 2m49s
[00:48:26.725] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:5:0)
[00:48:26.755] Build status: STARTED
[00:48:26.755] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:6:0) after 3m32s
[00:51:58.769] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:6:0)
[00:51:58.795] Build status: STARTED
[00:51:58.795] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:7:0) after 5m53s
[00:57:52.003] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:7:0)
[00:57:52.132] Build status: STARTED
[00:57:52.132] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:8:0) after 4m45s
[01:02:37.165] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:8:0)
[01:02:37.183] Build status: STARTED
[01:02:37.183] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:9:0) after 7m25s
[01:10:02.198] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:9:0)
[01:10:02.216] Build status: STARTED
[01:10:02.216] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:10:0) after 8m16s
[01:18:18.319] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:10:0)
[01:18:18.343] Build status: STARTED
[01:18:18.343] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:11:0) after 9m0s
[01:27:18.361] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:11:0)
[01:27:18.380] Build status: STARTED
[01:27:18.380] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:12:0) after 8m45s
[01:36:03.455] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:12:0)
[01:36:03.471] Build status: STARTED
[01:36:03.471] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:13:0) after 7m32s
[01:43:35.490] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:13:0)
[01:43:35.513] Build status: STARTED
[01:43:35.513] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:14:0) after 5m10s
[01:48:45.572] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:14:0)
[01:48:45.593] Build status: STARTED
[01:48:45.593] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:15:0) after 3m36s
[01:52:21.699] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:15:0)
[01:52:21.786] Build status: STARTED
[01:52:21.786] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:16:0) after 2m7s
[01:54:28.801] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:16:0)
[01:54:28.821] Build status: STARTED
[01:54:28.821] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:17:0) after 1m6s
[01:55:34.848] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:17:0)
[01:55:34.872] Build status: STARTED
[01:55:34.872] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:18:0) after 3m2s
[01:58:36.889] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:18:0)
[01:58:36.904] Build status: STARTED
[01:58:36.904] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:19:0) after 8m27s
[02:07:03.920] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:19:0)
[02:07:03.937] Build status: STARTED
[02:07:03.937] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:20:0) after 7m26s
[02:14:30.002] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:20:0)
[02:14:30.044] Build status: STARTED
[02:14:30.044] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:21:0) after 4m22s
[02:18:52.062] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:21:0)
[02:18:52.079] Build status: STARTED
[02:18:52.079] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:22:0) after 3m52s
[02:22:44.203] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:22:0)
[02:22:44.225] Build status: STARTED
[02:22:44.225] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:23:0) after 4m46s
[02:27:30.452] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:23:0)
[02:27:30.469] Build status: STARTED
[02:27:30.470] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:24:0) after 6m7s
[02:33:38.187] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:24:0)
[02:33:38.212] Build status: STARTED
[02:33:38.212] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:25:0) after 2m5s
[02:35:43.207] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:25:0)
[02:35:43.230] Build status: STARTED
[02:35:43.230] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:26:0) after 7m1s
[02:42:44.222] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:26:0)
[02:42:44.236] Build status: STARTED
[02:42:44.236] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:27:0) after 1m19s
[02:44:03.251] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:27:0)
[02:44:03.273] Build status: STARTED
[02:44:03.273] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:28:0) after 6m22s
[02:50:25.295] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:28:0)
[02:50:25.312] Build status: STARTED
[02:50:25.312] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:29:0) after 9m3s
[02:59:28.521] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:29:0)
[02:59:28.535] Build status: STARTED
[02:59:28.535] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:30:0) after 3m11s
[03:02:39.787] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:30:0)
[03:02:39.804] Build status: STARTED
[03:02:39.804] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:31:0) after 9m1s
[03:11:41.030] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:31:0)
[03:11:41.059] Build status: STARTED
[03:11:41.059] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:32:0) after 4m50s
[03:16:31.097] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:32:0)
[03:16:31.160] Build status: STARTED
[03:16:31.160] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:33:0) after 8m19s
[03:24:50.476] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:33:0)
[03:24:50.585] Build status: STARTED
[03:24:50.585] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:34:0) after 6m49s
[03:31:39.743] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:34:0)
[03:31:39.769] Build status: STARTED
[03:31:39.769] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:35:0) after 2m27s
[03:34:06.869] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:35:0)
[03:34:06.885] Build status: STARTED
[03:34:06.885] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:36:0) after 7m24s
[03:41:30.938] Handling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:36:0)
[03:41:30.975] Build status: STARTED
[03:41:30.975] Scheduling timer "check-buildbucket-build-status" (chromium-m140/win-official:8870312927551718896:37:0) after 9m29s
[03:47:09.374] Received PubSub notification, asking Buildbucket for the build status
[03:47:09.400] Build:
{
"id": "8704882865679994241",
"builder": {
"project": "chromium-m140",
"bucket": "ci",
"builder": "win-official"
},
"number": 236,
"createdBy": "project:chromium-m140",
"createTime": "2025-09-02T00:43:25.884880552Z",
"startTime": "2025-09-02T00:43:29.807210207Z",
"endTime": "2025-09-02T03:46:25.862516658Z",
"updateTime": "2025-09-02T03:46:25.862516658Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d473df1ea84faa28891aacf293cd04a9f381e5de",
"ref": "refs/branch-heads/7339"
}
}
}
[03:47:09.400] Invocation finished in 3h3m44.685191497s with status SUCCEEDED