[20:21:30.450] New invocation is queued and will start shortly
[20:21:31.674] Starting the invocation (attempt 1)
[20:21:31.703] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/43a9821dbbe6c2fe277764a671b5104466cae6b1"
[20:21:31.703] Popped gitiles commit info from properties and tags
[20:21:31.703] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:21:31.703] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:21:31.703] Buildbucket request:
{
"requestId": "8934743848275505904",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "43a9821dbbe6c2fe277764a671b5104466cae6b1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934743848275505904"
},
{
"key": "scheduler_job_id",
"value": "chromium/Windows deterministic"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:21:32.343] Scheduled build:
{
"id": "8769313786231180225",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 22356,
"createdBy": "project:chromium",
"createTime": "2023-09-21T20:21:31.772586064Z",
"updateTime": "2023-09-21T20:21:31.772586064Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "43a9821dbbe6c2fe277764a671b5104466cae6b1",
"ref": "refs/heads/main"
}
}
}
[20:21:32.343] Task URL: https://cr-buildbucket.appspot.com/build/8769313786231180225
[20:21:32.343] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:2:0) after 6m7s
[20:21:33.282] Received PubSub notification, asking Buildbucket for the build status
[20:21:33.299] Build status: SCHEDULED
[20:27:39.559] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:2:0)
[20:27:39.585] Build status: SCHEDULED
[20:27:39.585] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:4:0) after 8m25s
[20:28:46.666] Received PubSub notification, asking Buildbucket for the build status
[20:28:46.693] Build status: STARTED
[20:36:04.627] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:4:0)
[20:36:04.644] Build status: STARTED
[20:36:04.644] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:6:0) after 3m53s
[20:39:57.701] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:6:0)
[20:39:57.724] Build status: STARTED
[20:39:57.724] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:7:0) after 4m52s
[20:44:49.756] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:7:0)
[20:44:49.791] Build status: STARTED
[20:44:49.791] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:8:0) after 6m19s
[20:51:09.148] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:8:0)
[20:51:09.233] Build status: STARTED
[20:51:09.233] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:9:0) after 2m49s
[20:53:58.823] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:9:0)
[20:53:58.936] Build status: STARTED
[20:53:58.936] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:10:0) after 3m52s
[20:57:51.049] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:10:0)
[20:57:51.123] Build status: STARTED
[20:57:51.124] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:11:0) after 1m3s
[20:58:54.143] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:11:0)
[20:58:54.173] Build status: STARTED
[20:58:54.173] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:12:0) after 2m31s
[21:01:25.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:12:0)
[21:01:25.244] Build status: STARTED
[21:01:25.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:13:0) after 9m41s
[21:11:06.483] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:13:0)
[21:11:06.506] Build status: STARTED
[21:11:06.506] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:14:0) after 9m6s
[21:20:12.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:14:0)
[21:20:12.558] Build status: STARTED
[21:20:12.558] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:15:0) after 4m0s
[21:24:12.594] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:15:0)
[21:24:12.615] Build status: STARTED
[21:24:12.615] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:16:0) after 1m9s
[21:25:21.636] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:16:0)
[21:25:21.661] Build status: STARTED
[21:25:21.661] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:17:0) after 5m44s
[21:31:05.710] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:17:0)
[21:31:05.825] Build status: STARTED
[21:31:05.825] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:18:0) after 7m26s
[21:38:31.860] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:18:0)
[21:38:31.879] Build status: STARTED
[21:38:31.880] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:19:0) after 2m45s
[21:41:16.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:19:0)
[21:41:16.930] Build status: STARTED
[21:41:16.930] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:20:0) after 1m32s
[21:42:49.331] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:20:0)
[21:42:49.368] Build status: STARTED
[21:42:49.368] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:21:0) after 4m35s
[21:47:24.833] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:21:0)
[21:47:24.935] Build status: STARTED
[21:47:24.935] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:22:0) after 3m37s
[21:51:02.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:22:0)
[21:51:02.084] Build status: STARTED
[21:51:02.084] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:23:0) after 4m47s
[21:55:49.180] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:23:0)
[21:55:49.200] Build status: STARTED
[21:55:49.200] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:24:0) after 9m47s
[22:05:36.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:24:0)
[22:05:36.502] Build status: STARTED
[22:05:36.502] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:25:0) after 7m23s
[22:12:59.778] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:25:0)
[22:12:59.829] Build status: STARTED
[22:12:59.829] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:26:0) after 4m56s
[22:17:55.851] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:26:0)
[22:17:55.873] Build status: STARTED
[22:17:55.873] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:27:0) after 3m24s
[22:21:19.894] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:27:0)
[22:21:19.915] Build status: STARTED
[22:21:19.915] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:28:0) after 9m24s
[22:30:43.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:28:0)
[22:30:43.985] Build status: STARTED
[22:30:43.985] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:29:0) after 9m12s
[22:39:56.187] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:29:0)
[22:39:56.217] Build status: STARTED
[22:39:56.217] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:30:0) after 4m6s
[22:44:02.236] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:30:0)
[22:44:02.265] Build status: STARTED
[22:44:02.265] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:31:0) after 5m14s
[22:49:16.285] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:31:0)
[22:49:16.412] Build status: STARTED
[22:49:16.412] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:32:0) after 9m32s
[22:58:48.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:32:0)
[22:58:48.490] Build status: STARTED
[22:58:48.490] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:33:0) after 2m24s
[23:01:13.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:33:0)
[23:01:13.160] Build status: STARTED
[23:01:13.160] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:34:0) after 5m4s
[23:06:17.198] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:34:0)
[23:06:17.226] Build status: STARTED
[23:06:17.226] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:35:0) after 2m24s
[23:08:41.251] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:35:0)
[23:08:41.268] Build status: STARTED
[23:08:41.268] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:36:0) after 9m59s
[23:18:40.288] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:36:0)
[23:18:40.308] Build status: STARTED
[23:18:40.308] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:37:0) after 4m10s
[23:22:50.496] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:37:0)
[23:22:50.521] Build status: STARTED
[23:22:50.521] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:38:0) after 2m30s
[23:25:20.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:38:0)
[23:25:20.821] Build status: STARTED
[23:25:20.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:39:0) after 3m37s
[23:28:58.000] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:39:0)
[23:28:58.023] Build status: STARTED
[23:28:58.023] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:40:0) after 1m29s
[23:30:27.303] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:40:0)
[23:30:27.322] Build status: STARTED
[23:30:27.323] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:41:0) after 4m3s
[23:34:30.371] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:41:0)
[23:34:30.397] Build status: STARTED
[23:34:30.397] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:42:0) after 6m19s
[23:40:49.480] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:42:0)
[23:40:49.527] Build status: STARTED
[23:40:49.528] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:43:0) after 9m26s
[23:50:15.881] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:43:0)
[23:50:15.900] Build status: STARTED
[23:50:15.900] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:44:0) after 8m54s
[23:59:09.920] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:44:0)
[23:59:09.942] Build status: STARTED
[23:59:09.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:45:0) after 9m32s
[00:08:42.141] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:45:0)
[00:08:42.198] Build status: STARTED
[00:08:42.199] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:46:0) after 6m33s
[00:15:15.463] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:46:0)
[00:15:15.484] Build status: STARTED
[00:15:15.484] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:47:0) after 8m44s
[00:23:59.858] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:47:0)
[00:23:59.878] Build status: STARTED
[00:23:59.878] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:48:0) after 5m57s
[00:29:56.950] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:48:0)
[00:29:56.967] Build status: STARTED
[00:29:56.967] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:49:0) after 2m19s
[00:32:15.994] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:49:0)
[00:32:16.028] Build status: STARTED
[00:32:16.029] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:8934743848275505904:50:0) after 6m6s
[00:33:18.002] Received PubSub notification, asking Buildbucket for the build status
[00:33:18.018] Build:
{
"id": "8769313786231180225",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Windows deterministic"
},
"number": 22356,
"createdBy": "project:chromium",
"createTime": "2023-09-21T20:21:31.772586064Z",
"startTime": "2023-09-21T20:28:45.449634Z",
"endTime": "2023-09-22T00:33:17.728433520Z",
"updateTime": "2023-09-22T00:33:17.728433520Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "43a9821dbbe6c2fe277764a671b5104466cae6b1",
"ref": "refs/heads/main"
}
}
}
[00:33:18.018] Invocation finished in 4h11m47.579749769s with status SUCCEEDED