[04:12:05.094] New invocation is queued and will start shortly
[04:12:06.132] Starting the invocation (attempt 1)
[04:12:06.162] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/784dd1fd18a421ca798336a85ef4bc41af0f0e07"
[04:12:06.162] Popped gitiles commit info from properties and tags
[04:12:06.162] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:12:06.167] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:12:06.167] Buildbucket request:
{
"requestId": "8870028008090052176",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "784dd1fd18a421ca798336a85ef4bc41af0f0e07",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870028008090052176"
},
{
"key": "scheduler_job_id",
"value": "chromium/win-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[04:12:06.867] Scheduled build:
{
"id": "8704597946177951921",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 4838,
"createdBy": "project:chromium",
"createTime": "2025-09-05T04:12:06.295067950Z",
"updateTime": "2025-09-05T04:12:06.295067950Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "784dd1fd18a421ca798336a85ef4bc41af0f0e07",
"ref": "refs/heads/main"
}
}
}
[04:12:06.867] Task URL: https://cr-buildbucket.appspot.com/build/8704597946177951921
[04:12:06.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:2:0) after 4m56s
[04:12:07.915] Received PubSub notification, asking Buildbucket for the build status
[04:12:07.955] Build status: SCHEDULED
[04:12:11.635] Received PubSub notification, asking Buildbucket for the build status
[04:12:11.668] Build status: STARTED
[04:17:02.928] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:2:0)
[04:17:02.963] Build status: STARTED
[04:17:02.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:5:0) after 8m41s
[04:25:44.033] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:5:0)
[04:25:44.054] Build status: STARTED
[04:25:44.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:6:0) after 7m3s
[04:32:47.395] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:6:0)
[04:32:47.422] Build status: STARTED
[04:32:47.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:7:0) after 1m21s
[04:34:08.438] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:7:0)
[04:34:08.465] Build status: STARTED
[04:34:08.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:8:0) after 4m8s
[04:38:16.513] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:8:0)
[04:38:16.546] Build status: STARTED
[04:38:16.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:9:0) after 5m32s
[04:43:48.561] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:9:0)
[04:43:48.583] Build status: STARTED
[04:43:48.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:10:0) after 3m4s
[04:46:52.643] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:10:0)
[04:46:52.664] Build status: STARTED
[04:46:52.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:11:0) after 6m16s
[04:53:08.696] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:11:0)
[04:53:08.960] Build status: STARTED
[04:53:08.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:12:0) after 9m28s
[05:02:37.049] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:12:0)
[05:02:37.074] Build status: STARTED
[05:02:37.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:13:0) after 8m32s
[05:11:09.150] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:13:0)
[05:11:09.168] Build status: STARTED
[05:11:09.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:14:0) after 3m50s
[05:14:59.185] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:14:0)
[05:14:59.223] Build status: STARTED
[05:14:59.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:15:0) after 6m38s
[05:21:37.258] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:15:0)
[05:21:37.278] Build status: STARTED
[05:21:37.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:16:0) after 8m7s
[05:29:44.349] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:16:0)
[05:29:44.364] Build status: STARTED
[05:29:44.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:17:0) after 1m31s
[05:31:15.383] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:17:0)
[05:31:15.403] Build status: STARTED
[05:31:15.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:18:0) after 4m5s
[05:35:20.469] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:18:0)
[05:35:20.492] Build status: STARTED
[05:35:20.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:19:0) after 3m27s
[05:38:47.521] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:19:0)
[05:38:47.547] Build status: STARTED
[05:38:47.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:20:0) after 7m44s
[05:46:31.638] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:20:0)
[05:46:31.660] Build status: STARTED
[05:46:31.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:21:0) after 1m29s
[05:48:00.644] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:21:0)
[05:48:00.659] Build status: STARTED
[05:48:00.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:22:0) after 7m41s
[05:55:41.682] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:22:0)
[05:55:41.736] Build status: STARTED
[05:55:41.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:23:0) after 2m1s
[05:57:42.754] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:23:0)
[05:57:42.775] Build status: STARTED
[05:57:42.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:24:0) after 6m9s
[06:03:51.863] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:24:0)
[06:03:51.893] Build status: STARTED
[06:03:51.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:25:0) after 8m40s
[06:12:31.919] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:25:0)
[06:12:31.936] Build status: STARTED
[06:12:31.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:26:0) after 5m51s
[06:18:22.909] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:26:0)
[06:18:22.927] Build status: STARTED
[06:18:22.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:27:0) after 9m50s
[06:28:12.977] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:27:0)
[06:28:12.995] Build status: STARTED
[06:28:12.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:28:0) after 1m5s
[06:29:18.014] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:28:0)
[06:29:18.031] Build status: STARTED
[06:29:18.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:29:0) after 5m56s
[06:35:14.048] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:29:0)
[06:35:14.070] Build status: STARTED
[06:35:14.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:30:0) after 9m6s
[06:44:20.224] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:30:0)
[06:44:20.247] Build status: STARTED
[06:44:20.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:31:0) after 4m51s
[06:49:11.266] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:31:0)
[06:49:11.307] Build status: STARTED
[06:49:11.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:32:0) after 8m29s
[06:57:40.335] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:32:0)
[06:57:40.351] Build status: STARTED
[06:57:40.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:33:0) after 7m42s
[07:05:22.367] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:33:0)
[07:05:22.388] Build status: STARTED
[07:05:22.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:34:0) after 1m14s
[07:06:36.404] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:34:0)
[07:06:36.438] Build status: STARTED
[07:06:36.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:35:0) after 9m34s
[07:16:10.554] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:35:0)
[07:16:10.576] Build status: STARTED
[07:16:10.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:36:0) after 5m33s
[07:21:43.595] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:36:0)
[07:21:43.613] Build status: STARTED
[07:21:43.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:37:0) after 5m22s
[07:27:05.638] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:37:0)
[07:27:05.715] Build status: STARTED
[07:27:05.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:38:0) after 4m20s
[07:31:25.733] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:38:0)
[07:31:25.753] Build status: STARTED
[07:31:25.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:39:0) after 5m52s
[07:37:17.770] Handling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:39:0)
[07:37:17.789] Build status: STARTED
[07:37:17.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-build-perf-developer:8870028008090052176:40:0) after 5m42s
[07:40:41.631] Received PubSub notification, asking Buildbucket for the build status
[07:40:41.649] Build:
{
"id": "8704597946177951921",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "win-build-perf-developer"
},
"number": 4838,
"createdBy": "project:chromium",
"createTime": "2025-09-05T04:12:06.295067950Z",
"startTime": "2025-09-05T04:12:11.460195715Z",
"endTime": "2025-09-05T07:40:41.131969431Z",
"updateTime": "2025-09-05T07:40:41.131969431Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "784dd1fd18a421ca798336a85ef4bc41af0f0e07",
"ref": "refs/heads/main"
}
}
}
[07:40:41.649] Invocation finished in 3h28m36.561119736s with status SUCCEEDED