[10:27:48.876] New invocation is queued and will start shortly
[10:27:49.980] Starting the invocation (attempt 1)
[10:27:50.019] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d798a1cd7d0ef7c9466e4c7e13e58f0660049c7d"
[10:27:50.019] Popped gitiles commit info from properties and tags
[10:27:50.019] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:27:50.019] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:27:50.019] Buildbucket request:
{
"requestId": "9005718624899549792",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d798a1cd7d0ef7c9466e4c7e13e58f0660049c7d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9005718624899549792"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:27:50.556] Scheduled build:
{
"id": "8840288562929559568",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 13540,
"createdBy": "project:chromium",
"createTime": "2021-07-30T10:27:50.122077Z",
"updateTime": "2021-07-30T10:27:50.491736Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d798a1cd7d0ef7c9466e4c7e13e58f0660049c7d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[10:27:50.556] Task URL: https://cr-buildbucket.appspot.com/build/8840288562929559568
[10:27:50.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:2:0) after 2m53s
[10:28:57.168] Received PubSub notification, asking Buildbucket for the build status
[10:28:57.192] Build status: STARTED
[10:30:43.771] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:2:0)
[10:30:43.799] Build status: STARTED
[10:30:43.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:4:0) after 6m11s
[10:36:54.842] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:4:0)
[10:36:54.870] Build status: STARTED
[10:36:54.870] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:5:0) after 6m7s
[10:43:01.908] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:5:0)
[10:43:01.939] Build status: STARTED
[10:43:01.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:6:0) after 8m5s
[10:51:06.967] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:6:0)
[10:51:06.992] Build status: STARTED
[10:51:06.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:7:0) after 4m16s
[10:55:23.015] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:7:0)
[10:55:23.085] Build status: STARTED
[10:55:23.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:8:0) after 4m36s
[10:59:59.236] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:8:0)
[10:59:59.275] Build status: STARTED
[10:59:59.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:9:0) after 2m55s
[11:02:54.512] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:9:0)
[11:02:54.539] Build status: STARTED
[11:02:54.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:10:0) after 9m44s
[11:12:38.559] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:10:0)
[11:12:38.588] Build status: STARTED
[11:12:38.588] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:11:0) after 3m59s
[11:16:37.706] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:11:0)
[11:16:37.742] Build status: STARTED
[11:16:37.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:12:0) after 5m55s
[11:22:32.902] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:12:0)
[11:22:32.932] Build status: STARTED
[11:22:32.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:13:0) after 4m26s
[11:26:58.962] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:13:0)
[11:26:58.989] Build status: STARTED
[11:26:58.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:14:0) after 1m33s
[11:28:32.177] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:14:0)
[11:28:32.242] Build status: STARTED
[11:28:32.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:15:0) after 5m53s
[11:34:25.262] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:15:0)
[11:34:25.294] Build status: STARTED
[11:34:25.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:16:0) after 7m23s
[11:41:48.349] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:16:0)
[11:41:48.393] Build status: STARTED
[11:41:48.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:17:0) after 3m51s
[11:45:39.428] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:17:0)
[11:45:39.458] Build status: STARTED
[11:45:39.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:18:0) after 6m28s
[11:52:07.603] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:18:0)
[11:52:07.631] Build status: STARTED
[11:52:07.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:19:0) after 2m53s
[11:55:00.676] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:19:0)
[11:55:00.701] Build status: STARTED
[11:55:00.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:20:0) after 9m23s
[12:04:23.724] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:20:0)
[12:04:23.766] Build status: STARTED
[12:04:23.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:21:0) after 7m46s
[12:12:09.796] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:21:0)
[12:12:09.823] Build status: STARTED
[12:12:09.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:22:0) after 6m19s
[12:18:28.843] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:22:0)
[12:18:28.875] Build status: STARTED
[12:18:28.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:23:0) after 8m56s
[12:27:25.095] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:23:0)
[12:27:25.126] Build status: STARTED
[12:27:25.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:24:0) after 8m15s
[12:35:40.253] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:24:0)
[12:35:40.288] Build status: STARTED
[12:35:40.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:25:0) after 9m24s
[12:45:04.332] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:25:0)
[12:45:04.360] Build status: STARTED
[12:45:04.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:26:0) after 5m54s
[12:50:58.457] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:26:0)
[12:50:58.488] Build status: STARTED
[12:50:58.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:27:0) after 1m37s
[12:52:35.513] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:27:0)
[12:52:35.542] Build status: STARTED
[12:52:35.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:28:0) after 3m34s
[12:56:09.572] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:28:0)
[12:56:09.604] Build status: STARTED
[12:56:09.604] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:29:0) after 9m59s
[13:06:08.638] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:29:0)
[13:06:08.669] Build status: STARTED
[13:06:08.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:30:0) after 8m58s
[13:15:06.718] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:30:0)
[13:15:06.744] Build status: STARTED
[13:15:06.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:31:0) after 9m12s
[13:24:18.768] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:31:0)
[13:24:18.798] Build status: STARTED
[13:24:18.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:32:0) after 9m2s
[13:33:20.873] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:32:0)
[13:33:20.900] Build status: STARTED
[13:33:20.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:33:0) after 7m28s
[13:40:49.027] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:33:0)
[13:40:49.061] Build status: STARTED
[13:40:49.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:34:0) after 5m28s
[13:46:17.100] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:34:0)
[13:46:17.150] Build status: STARTED
[13:46:17.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:35:0) after 3m4s
[13:49:21.186] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:35:0)
[13:49:21.213] Build status: STARTED
[13:49:21.213] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:36:0) after 4m14s
[13:53:35.547] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:36:0)
[13:53:35.575] Build status: STARTED
[13:53:35.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:37:0) after 4m29s
[13:58:04.721] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:37:0)
[13:58:04.755] Build status: STARTED
[13:58:04.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:38:0) after 1m56s
[14:00:00.948] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:38:0)
[14:00:00.976] Build status: STARTED
[14:00:00.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:39:0) after 5m7s
[14:05:07.983] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:39:0)
[14:05:08.009] Build status: STARTED
[14:05:08.009] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:40:0) after 6m24s
[14:11:32.037] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:40:0)
[14:11:32.065] Build status: STARTED
[14:11:32.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:41:0) after 9m19s
[14:20:51.130] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:41:0)
[14:20:51.168] Build status: STARTED
[14:20:51.168] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:42:0) after 9m56s
[14:30:47.191] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:42:0)
[14:30:47.216] Build status: STARTED
[14:30:47.216] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:43:0) after 9m58s
[14:40:45.237] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:43:0)
[14:40:45.271] Build status: STARTED
[14:40:45.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:44:0) after 7m10s
[14:47:55.320] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:44:0)
[14:47:55.353] Build status: STARTED
[14:47:55.353] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:45:0) after 1m5s
[14:49:00.369] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:45:0)
[14:49:00.396] Build status: STARTED
[14:49:00.396] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:46:0) after 5m0s
[14:54:00.516] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:46:0)
[14:54:00.551] Build status: STARTED
[14:54:00.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:47:0) after 8m25s
[15:02:25.654] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:47:0)
[15:02:25.683] Build status: STARTED
[15:02:25.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:48:0) after 5m5s
[15:07:30.758] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:48:0)
[15:07:30.787] Build status: STARTED
[15:07:30.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:49:0) after 2m23s
[15:09:53.912] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:49:0)
[15:09:53.994] Build status: STARTED
[15:09:53.994] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:50:0) after 5m58s
[15:15:52.086] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:50:0)
[15:15:52.128] Build status: STARTED
[15:15:52.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:51:0) after 8m4s
[15:23:56.233] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:51:0)
[15:23:56.267] Build status: STARTED
[15:23:56.267] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:52:0) after 4m54s
[15:28:50.285] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:52:0)
[15:28:50.310] Build status: STARTED
[15:28:50.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:53:0) after 1m38s
[15:30:28.493] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:53:0)
[15:30:28.550] Build status: STARTED
[15:30:28.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:54:0) after 7m41s
[15:38:09.603] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:54:0)
[15:38:09.650] Build status: STARTED
[15:38:09.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9005718624899549792:55:0) after 8m19s
[15:40:50.986] Received PubSub notification, asking Buildbucket for the build status
[15:40:51.012] Build:
{
"id": "8840288562929559568",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 13540,
"createdBy": "project:chromium",
"createTime": "2021-07-30T10:27:50.122077Z",
"startTime": "2021-07-30T10:28:56.401903Z",
"endTime": "2021-07-30T15:40:50.713420852Z",
"updateTime": "2021-07-30T15:40:50.713892379Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d798a1cd7d0ef7c9466e4c7e13e58f0660049c7d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[15:40:51.012] Invocation finished in 5h13m2.152121527s with status SUCCEEDED