[22:02:56.504] New invocation is queued and will start shortly
[22:02:57.726] Starting the invocation (attempt 1)
[22:02:57.755] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/37fbeb94de05e454c490ce7431c476b74db7feab"
[22:02:57.755] Popped gitiles commit info from properties and tags
[22:02:57.755] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:02:57.756] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:02:57.756] Buildbucket request:
{
"requestId": "8908101958464034112",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "37fbeb94de05e454c490ce7431c476b74db7feab",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8908101958464034112"
},
{
"key": "scheduler_job_id",
"value": "chromium/Android x64 Builder All Targets (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:02:58.486] Scheduled build:
{
"id": "8742671896431558161",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 5213,
"createdBy": "project:chromium",
"createTime": "2024-07-11T22:02:57.814906722Z",
"updateTime": "2024-07-11T22:02:57.814906722Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "37fbeb94de05e454c490ce7431c476b74db7feab",
"ref": "refs/heads/main"
}
},
"canary": true
}
[22:02:58.486] Task URL: https://cr-buildbucket.appspot.com/build/8742671896431558161
[22:02:58.486] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:2:0) after 8m12s
[22:02:59.307] Received PubSub notification, asking Buildbucket for the build status
[22:02:59.418] Build status: SCHEDULED
[22:11:10.562] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:2:0)
[22:11:10.581] Build status: SCHEDULED
[22:11:10.581] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:4:0) after 8m59s
[22:20:09.709] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:4:0)
[22:20:09.739] Build status: SCHEDULED
[22:20:09.739] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:5:0) after 8m50s
[22:28:59.837] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:5:0)
[22:28:59.975] Build status: SCHEDULED
[22:28:59.975] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:6:0) after 4m16s
[22:33:15.992] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:6:0)
[22:33:16.493] Build status: SCHEDULED
[22:33:16.493] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:7:0) after 3m18s
[22:36:34.562] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:7:0)
[22:36:34.590] Build status: SCHEDULED
[22:36:34.590] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:8:0) after 7m12s
[22:43:46.608] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:8:0)
[22:43:46.635] Build status: SCHEDULED
[22:43:46.635] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:9:0) after 4m32s
[22:48:18.770] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:9:0)
[22:48:18.793] Build status: SCHEDULED
[22:48:18.793] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:10:0) after 6m1s
[22:52:20.179] Received PubSub notification, asking Buildbucket for the build status
[22:52:20.198] Build status: STARTED
[22:54:19.821] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:10:0)
[22:54:19.842] Build status: STARTED
[22:54:19.842] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:12:0) after 8m32s
[23:02:51.866] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:12:0)
[23:02:51.886] Build status: STARTED
[23:02:51.886] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:13:0) after 3m29s
[23:06:20.960] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:13:0)
[23:06:20.979] Build status: STARTED
[23:06:20.979] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:14:0) after 6m44s
[23:13:05.005] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:14:0)
[23:13:05.031] Build status: STARTED
[23:13:05.031] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:15:0) after 2m51s
[23:15:56.062] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:15:0)
[23:15:56.079] Build status: STARTED
[23:15:56.079] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:16:0) after 8m37s
[23:24:33.098] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:16:0)
[23:24:33.116] Build status: STARTED
[23:24:33.116] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:17:0) after 4m45s
[23:29:18.267] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:17:0)
[23:29:18.292] Build status: STARTED
[23:29:18.292] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:18:0) after 8m53s
[23:38:11.349] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:18:0)
[23:38:11.421] Build status: STARTED
[23:38:11.421] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:19:0) after 4m44s
[23:42:55.439] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:19:0)
[23:42:55.461] Build status: STARTED
[23:42:55.461] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:20:0) after 1m51s
[23:44:46.483] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:20:0)
[23:44:46.499] Build status: STARTED
[23:44:46.499] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:21:0) after 7m7s
[23:51:53.638] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:21:0)
[23:51:53.662] Build status: STARTED
[23:51:53.662] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:22:0) after 4m35s
[23:56:28.690] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:22:0)
[23:56:28.708] Build status: STARTED
[23:56:28.709] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:23:0) after 6m45s
[00:03:13.732] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:23:0)
[00:03:13.750] Build status: STARTED
[00:03:13.750] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:24:0) after 3m25s
[00:06:38.739] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:24:0)
[00:06:38.760] Build status: STARTED
[00:06:38.760] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:25:0) after 8m14s
[00:14:52.782] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:25:0)
[00:14:52.803] Build status: STARTED
[00:14:52.803] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:26:0) after 3m58s
[00:18:50.873] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:26:0)
[00:18:50.896] Build status: STARTED
[00:18:50.896] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:27:0) after 3m28s
[00:22:18.914] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:27:0)
[00:22:18.932] Build status: STARTED
[00:22:18.932] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:28:0) after 5m47s
[00:28:05.985] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:28:0)
[00:28:06.005] Build status: STARTED
[00:28:06.005] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:29:0) after 6m44s
[00:34:50.047] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:29:0)
[00:34:50.125] Build status: STARTED
[00:34:50.125] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:30:0) after 8m29s
[00:43:19.145] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:30:0)
[00:43:19.175] Build status: STARTED
[00:43:19.175] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:31:0) after 1m42s
[00:45:01.227] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:31:0)
[00:45:01.364] Build status: STARTED
[00:45:01.364] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:32:0) after 7m1s
[00:52:02.384] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:32:0)
[00:52:02.404] Build status: STARTED
[00:52:02.404] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:33:0) after 7m14s
[00:59:16.488] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:33:0)
[00:59:16.506] Build status: STARTED
[00:59:16.507] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:34:0) after 9m25s
[01:08:41.575] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:34:0)
[01:08:41.645] Build status: STARTED
[01:08:41.645] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:35:0) after 2m11s
[01:10:52.724] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:35:0)
[01:10:52.746] Build status: STARTED
[01:10:52.746] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:36:0) after 4m59s
[01:15:51.774] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:36:0)
[01:15:51.792] Build status: STARTED
[01:15:51.792] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:37:0) after 7m54s
[01:23:45.826] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:37:0)
[01:23:45.847] Build status: STARTED
[01:23:45.847] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:38:0) after 1m26s
[01:25:11.956] Handling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:38:0)
[01:25:11.976] Build status: STARTED
[01:25:11.976] Scheduling timer "check-buildbucket-build-status" (chromium/Android x64 Builder All Targets (dbg):8908101958464034112:39:0) after 9m2s
[01:34:00.261] Received PubSub notification, asking Buildbucket for the build status
[01:34:00.424] Build:
{
"id": "8742671896431558161",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Android x64 Builder All Targets (dbg)"
},
"number": 5213,
"createdBy": "project:chromium",
"createTime": "2024-07-11T22:02:57.814906722Z",
"startTime": "2024-07-11T22:52:19.881795116Z",
"endTime": "2024-07-12T01:33:59.242962161Z",
"updateTime": "2024-07-12T01:33:59.242962161Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "37fbeb94de05e454c490ce7431c476b74db7feab",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:34:00.424] Invocation finished in 3h31m3.932024063s with status SUCCEEDED