[11:43:06.233] New invocation is queued and will start shortly
[11:43:07.392] Starting the invocation (attempt 1)
[11:43:07.422] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/97884ac84b16b60444a6f7f40859955b10f765af"
[11:43:07.422] Popped gitiles commit info from properties and tags
[11:43:07.422] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:43:07.422] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:43:07.422] Buildbucket request:
{
"requestId": "8934957657344535040",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97884ac84b16b60444a6f7f40859955b10f765af",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934957657344535040"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:43:08.049] Scheduled build:
{
"id": "8769527595293017041",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-build-perf-developer"
},
"number": 3716,
"createdBy": "project:chromium",
"createTime": "2023-09-19T11:43:07.561218547Z",
"updateTime": "2023-09-19T11:43:07.561218547Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97884ac84b16b60444a6f7f40859955b10f765af",
"ref": "refs/heads/main"
}
}
}
[11:43:08.049] Task URL: https://cr-buildbucket.appspot.com/build/8769527595293017041
[11:43:08.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:2:0) after 2m18s
[11:43:09.144] Received PubSub notification, asking Buildbucket for the build status
[11:43:09.162] Build status: SCHEDULED
[11:43:12.016] Received PubSub notification, asking Buildbucket for the build status
[11:43:12.063] Build status: STARTED
[11:45:26.475] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:2:0)
[11:45:26.493] Build status: STARTED
[11:45:26.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:5:0) after 2m14s
[11:47:40.681] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:5:0)
[11:47:40.697] Build status: STARTED
[11:47:40.697] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:6:0) after 6m20s
[11:54:01.022] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:6:0)
[11:54:01.044] Build status: STARTED
[11:54:01.044] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:7:0) after 9m18s
[12:03:19.166] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:7:0)
[12:03:19.190] Build status: STARTED
[12:03:19.190] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:8:0) after 5m6s
[12:08:25.262] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:8:0)
[12:08:25.292] Build status: STARTED
[12:08:25.292] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:9:0) after 2m53s
[12:11:18.313] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:9:0)
[12:11:18.329] Build status: STARTED
[12:11:18.329] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:10:0) after 8m33s
[12:19:51.339] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:10:0)
[12:19:51.379] Build status: STARTED
[12:19:51.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:11:0) after 6m50s
[12:26:41.495] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:11:0)
[12:26:41.674] Build status: STARTED
[12:26:41.674] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:12:0) after 7m35s
[12:34:16.764] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:12:0)
[12:34:16.783] Build status: STARTED
[12:34:16.783] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:13:0) after 1m48s
[12:36:04.816] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:13:0)
[12:36:04.838] Build status: STARTED
[12:36:04.838] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:14:0) after 5m11s
[12:41:15.888] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:14:0)
[12:41:15.909] Build status: STARTED
[12:41:15.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:15:0) after 3m9s
[12:44:24.924] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:15:0)
[12:44:24.955] Build status: STARTED
[12:44:24.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:16:0) after 4m36s
[12:49:00.981] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:16:0)
[12:49:01.002] Build status: STARTED
[12:49:01.002] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:17:0) after 9m54s
[12:58:55.044] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:17:0)
[12:58:55.065] Build status: STARTED
[12:58:55.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:18:0) after 4m37s
[13:03:32.081] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:18:0)
[13:03:32.109] Build status: STARTED
[13:03:32.109] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:19:0) after 4m26s
[13:07:58.271] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:19:0)
[13:07:58.306] Build status: STARTED
[13:07:58.306] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:20:0) after 8m28s
[13:16:26.352] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:20:0)
[13:16:26.367] Build status: STARTED
[13:16:26.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:21:0) after 9m10s
[13:25:36.374] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:21:0)
[13:25:36.390] Build status: STARTED
[13:25:36.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:22:0) after 2m18s
[13:27:54.589] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:22:0)
[13:27:54.636] Build status: STARTED
[13:27:54.636] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:23:0) after 3m4s
[13:30:58.836] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:23:0)
[13:30:58.945] Build status: STARTED
[13:30:58.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:24:0) after 5m33s
[13:36:32.067] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:24:0)
[13:36:32.084] Build status: STARTED
[13:36:32.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:25:0) after 5m1s
[13:41:33.105] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:25:0)
[13:41:33.155] Build status: STARTED
[13:41:33.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:26:0) after 6m44s
[13:48:17.252] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:26:0)
[13:48:17.274] Build status: STARTED
[13:48:17.274] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:27:0) after 2m16s
[13:50:33.555] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:27:0)
[13:50:33.572] Build status: STARTED
[13:50:33.572] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:28:0) after 8m40s
[13:59:13.840] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:28:0)
[13:59:13.869] Build status: STARTED
[13:59:13.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:29:0) after 2m27s
[14:01:40.960] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:29:0)
[14:01:41.058] Build status: STARTED
[14:01:41.058] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:30:0) after 6m22s
[14:08:03.242] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:30:0)
[14:08:03.268] Build status: STARTED
[14:08:03.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:31:0) after 1m50s
[14:09:53.458] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:31:0)
[14:09:53.476] Build status: STARTED
[14:09:53.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:32:0) after 6m41s
[14:16:34.500] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:32:0)
[14:16:34.566] Build status: STARTED
[14:16:34.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:33:0) after 6m5s
[14:22:39.582] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:33:0)
[14:22:39.639] Build status: STARTED
[14:22:39.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:34:0) after 1m8s
[14:23:48.149] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:34:0)
[14:23:48.211] Build status: STARTED
[14:23:48.211] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:35:0) after 7m46s
[14:31:34.287] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:35:0)
[14:31:34.313] Build status: STARTED
[14:31:34.313] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:36:0) after 4m40s
[14:36:14.396] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:36:0)
[14:36:14.417] Build status: STARTED
[14:36:14.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:37:0) after 8m50s
[14:45:04.464] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:37:0)
[14:45:04.533] Build status: STARTED
[14:45:04.533] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:38:0) after 9m12s
[14:54:16.642] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:38:0)
[14:54:16.665] Build status: STARTED
[14:54:16.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:39:0) after 1m1s
[14:55:18.076] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:39:0)
[14:55:18.099] Build status: STARTED
[14:55:18.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:40:0) after 6m18s
[15:01:36.276] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:40:0)
[15:01:36.295] Build status: STARTED
[15:01:36.295] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:41:0) after 7m55s
[15:09:31.463] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:41:0)
[15:09:31.482] Build status: STARTED
[15:09:31.482] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:42:0) after 5m23s
[15:14:54.607] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:42:0)
[15:14:54.630] Build status: STARTED
[15:14:54.630] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:43:0) after 8m5s
[15:22:59.651] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:43:0)
[15:22:59.669] Build status: STARTED
[15:22:59.669] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:44:0) after 8m50s
[15:31:49.717] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:44:0)
[15:31:49.780] Build status: STARTED
[15:31:49.780] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:45:0) after 9m4s
[15:40:53.839] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:45:0)
[15:40:53.903] Build status: STARTED
[15:40:53.903] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:46:0) after 3m16s
[15:44:09.972] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:46:0)
[15:44:10.018] Build status: STARTED
[15:44:10.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:47:0) after 9m50s
[15:54:00.037] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:47:0)
[15:54:00.063] Build status: STARTED
[15:54:00.063] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:48:0) after 1m59s
[15:55:59.083] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:48:0)
[15:55:59.122] Build status: STARTED
[15:55:59.122] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:49:0) after 5m58s
[16:01:57.347] Handling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:49:0)
[16:01:57.366] Build status: STARTED
[16:01:57.366] Scheduling timer "check-buildbucket-build-status" (chromium/linux-build-perf-developer:8934957657344535040:50:0) after 9m54s
[16:06:11.668] Received PubSub notification, asking Buildbucket for the build status
[16:06:11.704] Build:
{
"id": "8769527595293017041",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-build-perf-developer"
},
"number": 3716,
"createdBy": "project:chromium",
"createTime": "2023-09-19T11:43:07.561218547Z",
"startTime": "2023-09-19T11:43:11.490283Z",
"endTime": "2023-09-19T16:06:10.179450960Z",
"updateTime": "2023-09-19T16:06:10.179450960Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "97884ac84b16b60444a6f7f40859955b10f765af",
"ref": "refs/heads/main"
}
}
}
[16:06:11.704] Invocation finished in 4h23m5.482552981s with status SUCCEEDED