[02:03:13.216] New invocation is queued and will start shortly
[02:03:14.322] Starting the invocation (attempt 1)
[02:03:14.350] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65c11c247e83900ecfc2209365ddb53386a696b6"
[02:03:14.350] Popped gitiles commit info from properties and tags
[02:03:14.350] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:03:14.352] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:03:14.352] Buildbucket request:
{
"requestId": "8926568622591613216",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "android-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65c11c247e83900ecfc2209365ddb53386a696b6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926568622591613216"
},
{
"key": "scheduler_job_id",
"value": "chromium/android-build-perf-developer"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[02:03:14.999] Scheduled build:
{
"id": "8761138560672267649",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "android-build-perf-developer"
},
"number": 305,
"createdBy": "project:chromium",
"createTime": "2023-12-21T02:03:14.418413363Z",
"updateTime": "2023-12-21T02:03:14.418413363Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65c11c247e83900ecfc2209365ddb53386a696b6",
"ref": "refs/heads/main"
}
}
}
[02:03:14.999] Task URL: https://cr-buildbucket.appspot.com/build/8761138560672267649
[02:03:14.999] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:2:0) after 2m16s
[02:03:15.909] Received PubSub notification, asking Buildbucket for the build status
[02:03:15.927] Build status: SCHEDULED
[02:03:16.865] Received PubSub notification, asking Buildbucket for the build status
[02:03:16.896] Build status: STARTED
[02:05:31.095] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:2:0)
[02:05:31.114] Build status: STARTED
[02:05:31.114] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:5:0) after 2m0s
[02:07:31.701] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:5:0)
[02:07:31.720] Build status: STARTED
[02:07:31.721] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:6:0) after 2m22s
[02:09:53.742] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:6:0)
[02:09:53.769] Build status: STARTED
[02:09:53.769] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:7:0) after 1m32s
[02:11:25.976] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:7:0)
[02:11:26.000] Build status: STARTED
[02:11:26.000] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:8:0) after 9m37s
[02:21:03.047] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:8:0)
[02:21:03.077] Build status: STARTED
[02:21:03.077] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:9:0) after 5m13s
[02:26:16.108] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:9:0)
[02:26:16.139] Build status: STARTED
[02:26:16.139] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:10:0) after 5m57s
[02:32:13.375] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:10:0)
[02:32:13.414] Build status: STARTED
[02:32:13.414] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:11:0) after 1m7s
[02:33:20.460] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:11:0)
[02:33:20.487] Build status: STARTED
[02:33:20.487] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:12:0) after 5m25s
[02:38:45.531] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:12:0)
[02:38:45.565] Build status: STARTED
[02:38:45.565] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:13:0) after 8m5s
[02:46:50.593] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:13:0)
[02:46:50.620] Build status: STARTED
[02:46:50.620] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:14:0) after 6m19s
[02:53:09.785] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:14:0)
[02:53:09.806] Build status: STARTED
[02:53:09.806] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:15:0) after 9m21s
[03:02:31.111] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:15:0)
[03:02:31.128] Build status: STARTED
[03:02:31.128] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:16:0) after 3m34s
[03:06:05.340] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:16:0)
[03:06:05.359] Build status: STARTED
[03:06:05.359] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:17:0) after 4m3s
[03:10:08.438] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:17:0)
[03:10:08.479] Build status: STARTED
[03:10:08.479] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:18:0) after 6m6s
[03:16:14.724] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:18:0)
[03:16:14.755] Build status: STARTED
[03:16:14.755] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:19:0) after 8m39s
[03:24:53.950] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:19:0)
[03:24:53.969] Build status: STARTED
[03:24:53.970] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:20:0) after 2m24s
[03:27:17.991] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:20:0)
[03:27:18.010] Build status: STARTED
[03:27:18.010] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:21:0) after 3m34s
[03:30:52.056] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:21:0)
[03:30:52.077] Build status: STARTED
[03:30:52.077] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:22:0) after 2m6s
[03:32:58.115] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:22:0)
[03:32:58.138] Build status: STARTED
[03:32:58.138] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:23:0) after 6m28s
[03:39:26.390] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:23:0)
[03:39:26.532] Build status: STARTED
[03:39:26.532] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:24:0) after 8m56s
[03:48:22.731] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:24:0)
[03:48:22.758] Build status: STARTED
[03:48:22.758] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:25:0) after 2m2s
[03:50:24.980] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:25:0)
[03:50:25.047] Build status: STARTED
[03:50:25.047] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:26:0) after 9m45s
[04:00:10.101] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:26:0)
[04:00:10.126] Build status: STARTED
[04:00:10.126] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:27:0) after 5m6s
[04:05:16.445] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:27:0)
[04:05:16.471] Build status: STARTED
[04:05:16.471] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:28:0) after 8m24s
[04:13:40.727] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:28:0)
[04:13:40.747] Build status: STARTED
[04:13:40.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:29:0) after 8m52s
[04:22:32.827] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:29:0)
[04:22:32.848] Build status: STARTED
[04:22:32.848] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:30:0) after 9m29s
[04:32:01.860] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:30:0)
[04:32:02.103] Build status: STARTED
[04:32:02.103] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:31:0) after 7m58s
[04:40:00.201] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:31:0)
[04:40:00.242] Build status: STARTED
[04:40:00.242] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:32:0) after 6m39s
[04:46:39.480] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:32:0)
[04:46:39.497] Build status: STARTED
[04:46:39.497] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:33:0) after 7m5s
[04:53:44.519] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:33:0)
[04:53:44.557] Build status: STARTED
[04:53:44.557] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:34:0) after 4m7s
[04:57:51.709] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:34:0)
[04:57:51.739] Build status: STARTED
[04:57:51.739] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:35:0) after 4m36s
[05:02:27.772] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:35:0)
[05:02:27.798] Build status: STARTED
[05:02:27.798] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:36:0) after 3m41s
[05:06:08.901] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:36:0)
[05:06:08.926] Build status: STARTED
[05:06:08.926] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:37:0) after 9m30s
[05:15:38.954] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:37:0)
[05:15:38.971] Build status: STARTED
[05:15:38.972] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:38:0) after 6m37s
[05:22:16.027] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:38:0)
[05:22:16.051] Build status: STARTED
[05:22:16.051] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:39:0) after 6m15s
[05:28:31.144] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:39:0)
[05:28:31.238] Build status: STARTED
[05:28:31.238] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:40:0) after 1m43s
[05:30:14.832] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:40:0)
[05:30:14.851] Build status: STARTED
[05:30:14.851] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:41:0) after 8m24s
[05:38:39.416] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:41:0)
[05:38:39.450] Build status: STARTED
[05:38:39.450] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:42:0) after 8m11s
[05:46:50.478] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:42:0)
[05:46:50.664] Build status: STARTED
[05:46:50.664] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:43:0) after 2m15s
[05:49:05.952] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:43:0)
[05:49:05.971] Build status: STARTED
[05:49:05.971] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:44:0) after 2m51s
[05:51:57.099] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:44:0)
[05:51:57.119] Build status: STARTED
[05:51:57.119] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:45:0) after 5m29s
[05:57:26.226] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:45:0)
[05:57:26.264] Build status: STARTED
[05:57:26.264] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:46:0) after 1m50s
[05:59:16.290] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:46:0)
[05:59:16.326] Build status: STARTED
[05:59:16.326] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:47:0) after 9m12s
[06:08:28.485] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:47:0)
[06:08:28.509] Build status: STARTED
[06:08:28.509] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:48:0) after 2m45s
[06:11:13.859] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:48:0)
[06:11:13.921] Build status: STARTED
[06:11:13.921] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:49:0) after 2m7s
[06:13:21.021] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:49:0)
[06:13:21.071] Build status: STARTED
[06:13:21.071] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:50:0) after 7m48s
[06:21:09.099] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:50:0)
[06:21:09.161] Build status: STARTED
[06:21:09.162] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:51:0) after 1m30s
[06:22:39.274] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:51:0)
[06:22:39.293] Build status: STARTED
[06:22:39.293] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:52:0) after 5m23s
[06:28:02.487] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:52:0)
[06:28:02.575] Build status: STARTED
[06:28:02.575] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:53:0) after 7m19s
[06:35:21.597] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:53:0)
[06:35:21.630] Build status: STARTED
[06:35:21.630] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:54:0) after 3m14s
[06:38:35.909] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:54:0)
[06:38:35.941] Build status: STARTED
[06:38:35.941] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:55:0) after 9m50s
[06:48:25.959] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:55:0)
[06:48:25.979] Build status: STARTED
[06:48:25.979] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8926568622591613216:56:0) after 4m18s
[06:51:59.710] Received PubSub notification, asking Buildbucket for the build status
[06:51:59.732] Build:
{
"id": "8761138560672267649",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "android-build-perf-developer"
},
"number": 305,
"createdBy": "project:chromium",
"createTime": "2023-12-21T02:03:14.418413363Z",
"startTime": "2023-12-21T02:03:15.364108Z",
"endTime": "2023-12-21T06:51:56.979720Z",
"updateTime": "2023-12-21T06:51:56.979720Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "65c11c247e83900ecfc2209365ddb53386a696b6",
"ref": "refs/heads/main"
}
}
}
[06:51:59.732] Invocation finished in 4h48m46.527746603s with status SUCCEEDED