[05:40:40.889] New invocation is queued and will start shortly
[05:40:42.089] Starting the invocation (attempt 1)
[05:40:42.116] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9e161961beba9673ad3237c4f5938bc1cdf97c14"
[05:40:42.116] Popped gitiles commit info from properties and tags
[05:40:42.116] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:40:42.116] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:40:42.117] Buildbucket request:
{
"requestId": "8885967500160279888",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "android-build-perf-developer"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e161961beba9673ad3237c4f5938bc1cdf97c14",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8885967500160279888"
},
{
"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"
}
}
[05:40:42.899] Scheduled build:
{
"id": "8720537438151725121",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "android-build-perf-developer"
},
"number": 3939,
"createdBy": "project:chromium",
"createTime": "2025-03-13T05:40:42.183945661Z",
"updateTime": "2025-03-13T05:40:42.183945661Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e161961beba9673ad3237c4f5938bc1cdf97c14",
"ref": "refs/heads/main"
}
}
}
[05:40:42.899] Task URL: https://cr-buildbucket.appspot.com/build/8720537438151725121
[05:40:42.899] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:2:0) after 5m21s
[05:40:43.901] Received PubSub notification, asking Buildbucket for the build status
[05:40:43.924] Build status: SCHEDULED
[05:42:15.558] Received PubSub notification, asking Buildbucket for the build status
[05:42:15.591] Build status: STARTED
[05:46:03.977] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:2:0)
[05:46:03.993] Build status: STARTED
[05:46:03.993] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:5:0) after 3m27s
[05:49:31.012] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:5:0)
[05:49:31.111] Build status: STARTED
[05:49:31.111] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:6:0) after 4m5s
[05:53:36.968] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:6:0)
[05:53:37.030] Build status: STARTED
[05:53:37.030] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:7:0) after 9m53s
[06:03:30.176] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:7:0)
[06:03:30.351] Build status: STARTED
[06:03:30.352] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:8:0) after 8m44s
[06:12:14.408] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:8:0)
[06:12:14.447] Build status: STARTED
[06:12:14.447] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:9:0) after 3m49s
[06:16:03.510] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:9:0)
[06:16:03.539] Build status: STARTED
[06:16:03.539] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:10:0) after 9m15s
[06:25:19.155] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:10:0)
[06:25:19.182] Build status: STARTED
[06:25:19.182] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:11:0) after 2m3s
[06:27:22.203] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:11:0)
[06:27:22.219] Build status: STARTED
[06:27:22.219] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:12:0) after 6m43s
[06:34:05.460] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:12:0)
[06:34:05.499] Build status: STARTED
[06:34:05.499] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:13:0) after 7m18s
[06:41:23.541] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:13:0)
[06:41:23.583] Build status: STARTED
[06:41:23.583] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:14:0) after 5m42s
[06:47:05.747] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:14:0)
[06:47:05.773] Build status: STARTED
[06:47:05.773] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:15:0) after 4m26s
[06:51:32.164] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:15:0)
[06:51:32.192] Build status: STARTED
[06:51:32.192] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:16:0) after 9m39s
[07:01:11.379] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:16:0)
[07:01:11.453] Build status: STARTED
[07:01:11.453] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:17:0) after 9m6s
[07:10:17.583] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:17:0)
[07:10:17.606] Build status: STARTED
[07:10:17.606] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:18:0) after 1m36s
[07:11:53.680] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:18:0)
[07:11:53.698] Build status: STARTED
[07:11:53.698] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:19:0) after 8m9s
[07:20:02.765] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:19:0)
[07:20:02.786] Build status: STARTED
[07:20:02.786] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:20:0) after 6m2s
[07:26:04.806] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:20:0)
[07:26:04.831] Build status: STARTED
[07:26:04.831] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:21:0) after 1m23s
[07:27:27.832] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:21:0)
[07:27:27.848] Build status: STARTED
[07:27:27.848] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:22:0) after 6m4s
[07:33:31.867] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:22:0)
[07:33:31.885] Build status: STARTED
[07:33:31.885] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:23:0) after 3m57s
[07:37:28.906] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:23:0)
[07:37:29.007] Build status: STARTED
[07:37:29.007] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:24:0) after 1m15s
[07:38:44.131] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:24:0)
[07:38:44.156] Build status: STARTED
[07:38:44.156] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:25:0) after 4m2s
[07:42:46.299] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:25:0)
[07:42:46.319] Build status: STARTED
[07:42:46.319] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:26:0) after 7m41s
[07:50:27.511] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:26:0)
[07:50:27.589] Build status: STARTED
[07:50:27.589] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:27:0) after 5m59s
[07:56:26.748] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:27:0)
[07:56:26.780] Build status: STARTED
[07:56:26.780] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:28:0) after 2m19s
[07:58:45.832] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:28:0)
[07:58:45.849] Build status: STARTED
[07:58:45.849] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:29:0) after 6m46s
[08:05:31.872] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:29:0)
[08:05:31.890] Build status: STARTED
[08:05:31.890] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:30:0) after 9m53s
[08:15:24.961] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:30:0)
[08:15:24.986] Build status: STARTED
[08:15:24.986] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:31:0) after 5m26s
[08:20:50.989] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:31:0)
[08:20:51.017] Build status: STARTED
[08:20:51.017] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:32:0) after 6m46s
[08:27:37.045] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:32:0)
[08:27:37.069] Build status: STARTED
[08:27:37.069] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:33:0) after 4m28s
[08:32:05.089] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:33:0)
[08:32:05.111] Build status: STARTED
[08:32:05.111] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:34:0) after 5m56s
[08:38:01.183] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:34:0)
[08:38:01.201] Build status: STARTED
[08:38:01.201] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:35:0) after 6m27s
[08:44:28.213] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:35:0)
[08:44:28.242] Build status: STARTED
[08:44:28.242] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:36:0) after 7m45s
[08:52:13.264] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:36:0)
[08:52:13.283] Build status: STARTED
[08:52:13.283] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:37:0) after 2m38s
[08:54:51.270] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:37:0)
[08:54:51.290] Build status: STARTED
[08:54:51.290] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:38:0) after 9m54s
[09:04:45.319] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:38:0)
[09:04:45.353] Build status: STARTED
[09:04:45.353] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:39:0) after 2m59s
[09:07:44.442] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:39:0)
[09:07:44.459] Build status: STARTED
[09:07:44.459] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:40:0) after 6m58s
[09:14:42.484] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:40:0)
[09:14:42.547] Build status: STARTED
[09:14:42.547] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:41:0) after 5m32s
[09:20:14.567] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:41:0)
[09:20:14.583] Build status: STARTED
[09:20:14.583] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:42:0) after 5m35s
[09:25:49.610] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:42:0)
[09:25:49.633] Build status: STARTED
[09:25:49.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:43:0) after 3m49s
[09:29:38.661] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:43:0)
[09:29:38.682] Build status: STARTED
[09:29:38.682] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:44:0) after 3m42s
[09:33:20.750] Handling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:44:0)
[09:33:20.775] Build status: STARTED
[09:33:20.775] Scheduling timer "check-buildbucket-build-status" (chromium/android-build-perf-developer:8885967500160279888:45:0) after 8m2s
[09:35:36.286] Received PubSub notification, asking Buildbucket for the build status
[09:35:36.351] Build:
{
"id": "8720537438151725121",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "android-build-perf-developer"
},
"number": 3939,
"createdBy": "project:chromium",
"createTime": "2025-03-13T05:40:42.183945661Z",
"startTime": "2025-03-13T05:42:01.535312837Z",
"endTime": "2025-03-13T09:35:35.111815233Z",
"updateTime": "2025-03-13T09:35:35.111815233Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e161961beba9673ad3237c4f5938bc1cdf97c14",
"ref": "refs/heads/main"
}
}
}
[09:35:36.351] Invocation finished in 3h54m55.466456714s with status SUCCEEDED