[14:47:32.892] New invocation is queued and will start shortly
[14:47:34.088] Starting the invocation (attempt 1)
[14:47:34.116] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9e7de59e6c78ef3c3b23409f83dc19616732483a"
[14:47:34.116] Popped gitiles commit info from properties and tags
[14:47:34.116] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:47:34.116] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:47:34.116] Buildbucket request:
{
"requestId": "8897801296890154448",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e7de59e6c78ef3c3b23409f83dc19616732483a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897801296890154448"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:47:34.951] Scheduled build:
{
"id": "8732371234887316977",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 35581,
"createdBy": "project:chromium",
"createTime": "2024-11-02T14:47:34.171087298Z",
"updateTime": "2024-11-02T14:47:34.171087298Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e7de59e6c78ef3c3b23409f83dc19616732483a",
"ref": "refs/heads/main"
}
}
}
[14:47:34.951] Task URL: https://cr-buildbucket.appspot.com/build/8732371234887316977
[14:47:34.951] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:2:0) after 4m5s
[14:47:35.902] Received PubSub notification, asking Buildbucket for the build status
[14:47:35.923] Build status: SCHEDULED
[14:47:40.766] Received PubSub notification, asking Buildbucket for the build status
[14:47:40.789] Build status: STARTED
[14:51:40.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:2:0)
[14:51:40.086] Build status: STARTED
[14:51:40.086] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:5:0) after 9m50s
[15:01:30.215] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:5:0)
[15:01:30.235] Build status: STARTED
[15:01:30.235] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:6:0) after 2m23s
[15:03:53.264] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:6:0)
[15:03:53.282] Build status: STARTED
[15:03:53.282] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:7:0) after 5m23s
[15:09:16.298] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:7:0)
[15:09:16.322] Build status: STARTED
[15:09:16.322] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:8:0) after 7m34s
[15:16:50.338] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:8:0)
[15:16:50.356] Build status: STARTED
[15:16:50.356] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:9:0) after 1m33s
[15:18:23.641] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:9:0)
[15:18:23.661] Build status: STARTED
[15:18:23.661] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:10:0) after 8m47s
[15:27:10.775] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:10:0)
[15:27:10.794] Build status: STARTED
[15:27:10.794] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:11:0) after 9m38s
[15:36:48.856] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:11:0)
[15:36:48.875] Build status: STARTED
[15:36:48.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:12:0) after 6m57s
[15:43:45.896] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:12:0)
[15:43:45.940] Build status: STARTED
[15:43:45.940] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:13:0) after 3m50s
[15:47:36.156] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:13:0)
[15:47:36.174] Build status: STARTED
[15:47:36.174] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:14:0) after 9m10s
[15:56:46.215] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:14:0)
[15:56:46.232] Build status: STARTED
[15:56:46.232] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:15:0) after 7m28s
[16:04:14.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:15:0)
[16:04:14.309] Build status: STARTED
[16:04:14.309] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:16:0) after 1m26s
[16:05:40.471] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:16:0)
[16:05:40.539] Build status: STARTED
[16:05:40.539] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:17:0) after 9m27s
[16:15:07.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:17:0)
[16:15:07.578] Build status: STARTED
[16:15:07.578] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:18:0) after 3m33s
[16:18:40.634] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:18:0)
[16:18:40.652] Build status: STARTED
[16:18:40.652] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:19:0) after 1m51s
[16:20:31.666] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:19:0)
[16:20:31.686] Build status: STARTED
[16:20:31.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:20:0) after 8m50s
[16:29:21.703] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:20:0)
[16:29:21.720] Build status: STARTED
[16:29:21.720] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:21:0) after 8m22s
[16:37:43.841] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:21:0)
[16:37:43.860] Build status: STARTED
[16:37:43.860] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:22:0) after 8m57s
[16:46:40.976] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:22:0)
[16:46:40.996] Build status: STARTED
[16:46:40.996] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:23:0) after 3m14s
[16:49:55.133] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:23:0)
[16:49:55.153] Build status: STARTED
[16:49:55.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:24:0) after 9m40s
[16:59:35.172] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:24:0)
[16:59:35.199] Build status: STARTED
[16:59:35.199] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:25:0) after 4m24s
[17:03:59.313] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:25:0)
[17:03:59.330] Build status: STARTED
[17:03:59.330] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:26:0) after 6m34s
[17:10:33.451] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:26:0)
[17:10:33.472] Build status: STARTED
[17:10:33.472] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:27:0) after 7m25s
[17:17:58.491] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:27:0)
[17:17:58.512] Build status: STARTED
[17:17:58.512] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:28:0) after 3m38s
[17:21:36.528] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:28:0)
[17:21:36.553] Build status: STARTED
[17:21:36.553] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:29:0) after 8m4s
[17:29:40.570] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:29:0)
[17:29:40.626] Build status: STARTED
[17:29:40.626] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:30:0) after 7m23s
[17:37:03.752] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:30:0)
[17:37:03.772] Build status: STARTED
[17:37:03.772] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:31:0) after 3m56s
[17:40:59.788] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:31:0)
[17:40:59.807] Build status: STARTED
[17:40:59.807] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:32:0) after 9m16s
[17:50:15.822] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:32:0)
[17:50:15.845] Build status: STARTED
[17:50:15.845] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:33:0) after 5m1s
[17:55:16.905] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:33:0)
[17:55:16.927] Build status: STARTED
[17:55:16.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:34:0) after 5m26s
[18:00:43.056] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:34:0)
[18:00:43.072] Build status: STARTED
[18:00:43.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:35:0) after 5m14s
[18:05:57.122] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:35:0)
[18:05:57.143] Build status: STARTED
[18:05:57.143] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:36:0) after 9m47s
[18:15:44.203] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:36:0)
[18:15:44.219] Build status: STARTED
[18:15:44.219] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:37:0) after 6m30s
[18:22:14.244] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:37:0)
[18:22:14.263] Build status: STARTED
[18:22:14.263] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:38:0) after 9m8s
[18:31:22.360] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:38:0)
[18:31:22.384] Build status: STARTED
[18:31:22.384] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:39:0) after 4m40s
[18:36:02.630] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:39:0)
[18:36:02.644] Build status: STARTED
[18:36:02.645] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:40:0) after 5m42s
[18:41:44.760] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:40:0)
[18:41:44.778] Build status: STARTED
[18:41:44.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:41:0) after 3m14s
[18:44:58.974] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:41:0)
[18:44:59.158] Build status: STARTED
[18:44:59.158] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:42:0) after 2m40s
[18:47:39.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:42:0)
[18:47:39.215] Build status: STARTED
[18:47:39.215] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:43:0) after 7m9s
[18:54:48.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:43:0)
[18:54:48.285] Build status: STARTED
[18:54:48.285] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:44:0) after 7m37s
[19:02:25.457] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:44:0)
[19:02:25.478] Build status: STARTED
[19:02:25.478] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:45:0) after 9m59s
[19:12:24.497] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:45:0)
[19:12:24.511] Build status: STARTED
[19:12:24.511] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8897801296890154448:46:0) after 7m5s
[19:17:57.750] Received PubSub notification, asking Buildbucket for the build status
[19:17:57.768] Build:
{
"id": "8732371234887316977",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 35581,
"createdBy": "project:chromium",
"createTime": "2024-11-02T14:47:34.171087298Z",
"startTime": "2024-11-02T14:47:40.481630217Z",
"endTime": "2024-11-02T19:17:57.358314652Z",
"updateTime": "2024-11-02T19:17:57.358314652Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9e7de59e6c78ef3c3b23409f83dc19616732483a",
"ref": "refs/heads/main"
}
}
}
[19:17:57.768] Invocation finished in 4h30m24.890556399s with status SUCCEEDED