[01:43:21.474] New invocation is queued and will start shortly
[01:43:22.557] Starting the invocation (attempt 1)
[01:43:22.579] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31f490c2ebdf4f185e0c8e4ae4999e07fcb94060"
[01:43:22.579] Popped gitiles commit info from properties and tags
[01:43:22.579] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:43:22.580] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:43:22.580] Buildbucket request:
{
"requestId": "8919322114910304384",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "chromeos-amd64-generic-rel"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "31f490c2ebdf4f185e0c8e4ae4999e07fcb94060",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8919322114910304384"
},
{
"key": "scheduler_job_id",
"value": "chromium/chromeos-amd64-generic-rel"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:43:24.351] Scheduled build:
{
"id": "8753892053007450721",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "chromeos-amd64-generic-rel"
},
"number": 82331,
"createdBy": "project:chromium",
"createTime": "2024-03-10T01:43:22.662763071Z",
"updateTime": "2024-03-10T01:43:22.662763071Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "31f490c2ebdf4f185e0c8e4ae4999e07fcb94060",
"ref": "refs/heads/main"
}
}
}
[01:43:24.351] Task URL: https://cr-buildbucket.appspot.com/build/8753892053007450721
[01:43:24.351] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:2:0) after 4m47s
[01:43:25.273] Received PubSub notification, asking Buildbucket for the build status
[01:43:25.338] Build status: SCHEDULED
[01:48:11.371] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:2:0)
[01:48:11.389] Build status: SCHEDULED
[01:48:11.389] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:4:0) after 5m14s
[01:53:25.428] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:4:0)
[01:53:25.463] Build status: SCHEDULED
[01:53:25.463] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:5:0) after 9m58s
[01:55:42.517] Received PubSub notification, asking Buildbucket for the build status
[01:55:42.539] Build status: STARTED
[02:03:23.521] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:5:0)
[02:03:23.546] Build status: STARTED
[02:03:23.546] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:7:0) after 5m32s
[02:08:55.562] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:7:0)
[02:08:55.658] Build status: STARTED
[02:08:55.658] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:8:0) after 7m2s
[02:15:57.673] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:8:0)
[02:15:57.690] Build status: STARTED
[02:15:57.690] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:9:0) after 5m45s
[02:21:42.704] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:9:0)
[02:21:42.721] Build status: STARTED
[02:21:42.721] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:10:0) after 6m38s
[02:28:21.708] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:10:0)
[02:28:21.735] Build status: STARTED
[02:28:21.735] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:11:0) after 1m27s
[02:29:48.901] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:11:0)
[02:29:48.933] Build status: STARTED
[02:29:48.933] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:12:0) after 3m53s
[02:33:42.074] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:12:0)
[02:33:42.106] Build status: STARTED
[02:33:42.106] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:13:0) after 1m40s
[02:35:22.131] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:13:0)
[02:35:22.168] Build status: STARTED
[02:35:22.168] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:14:0) after 3m49s
[02:39:11.255] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:14:0)
[02:39:11.281] Build status: STARTED
[02:39:11.281] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:15:0) after 5m38s
[02:44:49.306] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:15:0)
[02:44:49.333] Build status: STARTED
[02:44:49.333] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:16:0) after 1m7s
[02:45:56.354] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:16:0)
[02:45:56.372] Build status: STARTED
[02:45:56.372] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:17:0) after 2m5s
[02:48:01.385] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:17:0)
[02:48:01.404] Build status: STARTED
[02:48:01.404] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:18:0) after 8m7s
[02:56:09.451] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:18:0)
[02:56:09.477] Build status: STARTED
[02:56:09.477] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:19:0) after 3m33s
[02:59:43.469] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:19:0)
[02:59:43.490] Build status: STARTED
[02:59:43.491] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:20:0) after 5m46s
[03:05:29.565] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:20:0)
[03:05:29.626] Build status: STARTED
[03:05:29.626] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:21:0) after 6m6s
[03:11:35.858] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:21:0)
[03:11:36.014] Build status: STARTED
[03:11:36.014] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:22:0) after 5m26s
[03:17:02.033] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:22:0)
[03:17:02.052] Build status: STARTED
[03:17:02.052] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:23:0) after 8m23s
[03:25:25.068] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:23:0)
[03:25:25.158] Build status: STARTED
[03:25:25.159] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:24:0) after 8m11s
[03:33:36.174] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:24:0)
[03:33:36.192] Build status: STARTED
[03:33:36.192] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:25:0) after 1m16s
[03:34:52.214] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:25:0)
[03:34:52.234] Build status: STARTED
[03:34:52.234] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:26:0) after 4m19s
[03:39:11.321] Handling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:26:0)
[03:39:11.362] Build status: STARTED
[03:39:11.362] Scheduling timer "check-buildbucket-build-status" (chromium/chromeos-amd64-generic-rel:8919322114910304384:27:0) after 3m22s
[03:42:31.580] Received PubSub notification, asking Buildbucket for the build status
[03:42:31.604] Build:
{
"id": "8753892053007450721",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "chromeos-amd64-generic-rel"
},
"number": 82331,
"createdBy": "project:chromium",
"createTime": "2024-03-10T01:43:22.662763071Z",
"startTime": "2024-03-10T01:55:41.976115Z",
"endTime": "2024-03-10T03:42:30.363819Z",
"updateTime": "2024-03-10T03:42:30.363819Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "31f490c2ebdf4f185e0c8e4ae4999e07fcb94060",
"ref": "refs/heads/main"
}
}
}
[03:42:31.604] Invocation finished in 1h59m10.14099776s with status SUCCEEDED