[14:47:09.043] New invocation is queued and will start shortly
[14:47:10.362] Starting the invocation (attempt 1)
[14:47:10.388] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6387869180068098a6a33a35a96d2fb365997810"
[14:47:10.388] Popped gitiles commit info from properties and tags
[14:47:10.388] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:47:10.389] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:47:10.389] Buildbucket request:
{
"requestId": "8906226839763956544",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-chromeos-build-perf-siso"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6387869180068098a6a33a35a96d2fb365997810",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8906226839763956544"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-chromeos-build-perf-siso"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:47:11.214] Scheduled build:
{
"id": "8740796777620439185",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-chromeos-build-perf-siso"
},
"number": 3327,
"createdBy": "project:chromium",
"createTime": "2024-08-01T14:47:10.464181852Z",
"updateTime": "2024-08-01T14:47:10.464181852Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6387869180068098a6a33a35a96d2fb365997810",
"ref": "refs/heads/main"
}
}
}
[14:47:11.214] Task URL: https://cr-buildbucket.appspot.com/build/8740796777620439185
[14:47:11.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:2:0) after 2m48s
[14:47:12.216] Received PubSub notification, asking Buildbucket for the build status
[14:47:12.244] Build status: SCHEDULED
[14:47:16.893] Received PubSub notification, asking Buildbucket for the build status
[14:47:16.925] Build status: STARTED
[14:49:59.240] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:2:0)
[14:49:59.280] Build status: STARTED
[14:49:59.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:5:0) after 4m47s
[14:54:46.302] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:5:0)
[14:54:46.328] Build status: STARTED
[14:54:46.328] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:6:0) after 4m7s
[14:58:53.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:6:0)
[14:58:53.523] Build status: STARTED
[14:58:53.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:7:0) after 4m24s
[15:03:17.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:7:0)
[15:03:17.578] Build status: STARTED
[15:03:17.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:8:0) after 9m18s
[15:12:35.631] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:8:0)
[15:12:35.655] Build status: STARTED
[15:12:35.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:9:0) after 8m38s
[15:21:13.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:9:0)
[15:21:13.706] Build status: STARTED
[15:21:13.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:10:0) after 3m1s
[15:24:14.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:10:0)
[15:24:14.764] Build status: STARTED
[15:24:14.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:11:0) after 2m51s
[15:27:05.810] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:11:0)
[15:27:05.870] Build status: STARTED
[15:27:05.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:12:0) after 6m3s
[15:33:08.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:12:0)
[15:33:08.995] Build status: STARTED
[15:33:08.996] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:13:0) after 2m30s
[15:35:39.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:13:0)
[15:35:39.111] Build status: STARTED
[15:35:39.112] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:14:0) after 9m19s
[15:44:58.132] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:14:0)
[15:44:58.161] Build status: STARTED
[15:44:58.161] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:15:0) after 8m39s
[15:53:37.710] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:15:0)
[15:53:37.728] Build status: STARTED
[15:53:37.728] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:16:0) after 2m10s
[15:55:49.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:16:0)
[15:55:49.023] Build status: STARTED
[15:55:49.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:17:0) after 6m38s
[16:02:27.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:17:0)
[16:02:27.148] Build status: STARTED
[16:02:27.148] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:18:0) after 9m2s
[16:11:29.155] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:18:0)
[16:11:29.179] Build status: STARTED
[16:11:29.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:19:0) after 1m48s
[16:13:17.195] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:19:0)
[16:13:17.240] Build status: STARTED
[16:13:17.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:20:0) after 3m25s
[16:16:42.277] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:20:0)
[16:16:42.344] Build status: STARTED
[16:16:42.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:21:0) after 9m22s
[16:26:04.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:21:0)
[16:26:04.377] Build status: STARTED
[16:26:04.377] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:22:0) after 2m56s
[16:29:00.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:22:0)
[16:29:00.433] Build status: STARTED
[16:29:00.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:23:0) after 9m8s
[16:38:08.458] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:23:0)
[16:38:08.490] Build status: STARTED
[16:38:08.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:24:0) after 8m32s
[16:46:40.546] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:24:0)
[16:46:40.565] Build status: STARTED
[16:46:40.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:25:0) after 7m1s
[16:53:41.585] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:25:0)
[16:53:41.609] Build status: STARTED
[16:53:41.609] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:26:0) after 2m24s
[16:56:05.665] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:26:0)
[16:56:05.685] Build status: STARTED
[16:56:05.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:27:0) after 1m28s
[16:57:33.707] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:27:0)
[16:57:33.751] Build status: STARTED
[16:57:33.751] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:28:0) after 4m53s
[17:02:26.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:28:0)
[17:02:26.805] Build status: STARTED
[17:02:26.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:29:0) after 9m41s
[17:12:07.923] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:29:0)
[17:12:07.945] Build status: STARTED
[17:12:07.945] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:30:0) after 2m47s
[17:14:55.072] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:30:0)
[17:14:55.099] Build status: STARTED
[17:14:55.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:31:0) after 1m22s
[17:16:17.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:31:0)
[17:16:17.443] Build status: STARTED
[17:16:17.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:32:0) after 9m38s
[17:25:55.479] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:32:0)
[17:25:55.508] Build status: STARTED
[17:25:55.508] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:33:0) after 6m34s
[17:32:29.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:33:0)
[17:32:29.583] Build status: STARTED
[17:32:29.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:34:0) after 2m17s
[17:34:46.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:34:0)
[17:34:46.643] Build status: STARTED
[17:34:46.643] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:35:0) after 2m21s
[17:37:07.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:35:0)
[17:37:07.746] Build status: STARTED
[17:37:07.746] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:36:0) after 3m39s
[17:40:47.017] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:36:0)
[17:40:47.035] Build status: STARTED
[17:40:47.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:37:0) after 7m45s
[17:48:32.057] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:37:0)
[17:48:32.197] Build status: STARTED
[17:48:32.197] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:38:0) after 9m35s
[17:58:07.298] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:38:0)
[17:58:07.317] Build status: STARTED
[17:58:07.317] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:39:0) after 6m55s
[18:05:02.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:39:0)
[18:05:02.365] Build status: STARTED
[18:05:02.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:40:0) after 9m5s
[18:14:07.400] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:40:0)
[18:14:07.417] Build status: STARTED
[18:14:07.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:41:0) after 6m46s
[18:20:53.445] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:41:0)
[18:20:53.516] Build status: STARTED
[18:20:53.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-build-perf-siso:8906226839763956544:42:0) after 1m25s
[18:22:03.098] Received PubSub notification, asking Buildbucket for the build status
[18:22:03.116] Build:
{
"id": "8740796777620439185",
"builder": {
"project": "chromium",
"bucket": "build",
"builder": "linux-chromeos-build-perf-siso"
},
"number": 3327,
"createdBy": "project:chromium",
"createTime": "2024-08-01T14:47:10.464181852Z",
"startTime": "2024-08-01T14:47:16.643965895Z",
"endTime": "2024-08-01T18:22:02.705149456Z",
"updateTime": "2024-08-01T18:22:02.705149456Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6387869180068098a6a33a35a96d2fb365997810",
"ref": "refs/heads/main"
}
}
}
[18:22:03.116] Invocation finished in 3h34m54.079535201s with status SUCCEEDED