[01:24:34.275] New invocation is queued and will start shortly
[01:24:35.357] Starting the invocation (attempt 1)
[01:24:35.399] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02945f6d0c08adf6aa3e53e35a9045a35f0cdddd"
[01:24:35.399] Popped gitiles commit info from properties and tags
[01:24:35.399] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:24:35.399] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:24:35.399] Buildbucket request:
{
"requestId": "9014721902487727056",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02945f6d0c08adf6aa3e53e35a9045a35f0cdddd",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9014721902487727056"
},
{
"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"
}
}
[01:24:35.920] Scheduled build:
{
"id": "8849291840553921232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 12548,
"createdBy": "project:chromium",
"createTime": "2021-04-22T01:24:35.488078Z",
"updateTime": "2021-04-22T01:24:35.843231Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02945f6d0c08adf6aa3e53e35a9045a35f0cdddd",
"ref": "refs/heads/master"
}
}
}
[01:24:35.920] Task URL: https://cr-buildbucket.appspot.com/build/8849291840553921232
[01:24:35.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:2:0) after 1m47s
[01:24:38.516] Received PubSub notification, asking Buildbucket for the build status
[01:24:38.563] Build status: STARTED
[01:26:23.001] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:2:0)
[01:26:23.028] Build status: STARTED
[01:26:23.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:4:0) after 2m31s
[01:28:54.202] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:4:0)
[01:28:54.243] Build status: STARTED
[01:28:54.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:5:0) after 3m41s
[01:32:35.386] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:5:0)
[01:32:35.414] Build status: STARTED
[01:32:35.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:6:0) after 5m9s
[01:37:44.663] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:6:0)
[01:37:44.687] Build status: STARTED
[01:37:44.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:7:0) after 6m54s
[01:44:38.715] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:7:0)
[01:44:38.766] Build status: STARTED
[01:44:38.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:8:0) after 1m22s
[01:46:00.805] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:8:0)
[01:46:00.833] Build status: STARTED
[01:46:00.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:9:0) after 1m5s
[01:47:05.856] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:9:0)
[01:47:05.883] Build status: STARTED
[01:47:05.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:10:0) after 6m23s
[01:53:28.952] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:10:0)
[01:53:28.987] Build status: STARTED
[01:53:28.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:11:0) after 4m32s
[01:58:01.060] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:11:0)
[01:58:01.138] Build status: STARTED
[01:58:01.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:12:0) after 6m11s
[02:04:12.120] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:12:0)
[02:04:12.153] Build status: STARTED
[02:04:12.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:13:0) after 8m33s
[02:12:45.174] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:13:0)
[02:12:45.199] Build status: STARTED
[02:12:45.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:14:0) after 1m55s
[02:14:40.470] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:14:0)
[02:14:40.529] Build status: STARTED
[02:14:40.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:15:0) after 6m58s
[02:21:38.623] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:15:0)
[02:21:38.657] Build status: STARTED
[02:21:38.657] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:16:0) after 6m44s
[02:28:22.660] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:16:0)
[02:28:22.682] Build status: STARTED
[02:28:22.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:17:0) after 5m40s
[02:34:02.773] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:17:0)
[02:34:02.802] Build status: STARTED
[02:34:02.802] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:18:0) after 7m30s
[02:41:32.829] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:18:0)
[02:41:32.899] Build status: STARTED
[02:41:32.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:19:0) after 5m19s
[02:46:51.923] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:19:0)
[02:46:51.952] Build status: STARTED
[02:46:51.952] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:20:0) after 2m10s
[02:49:02.019] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:20:0)
[02:49:02.047] Build status: STARTED
[02:49:02.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:21:0) after 3m54s
[02:52:56.124] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:21:0)
[02:52:56.196] Build status: STARTED
[02:52:56.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:22:0) after 7m7s
[03:00:03.522] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:22:0)
[03:00:03.566] Build status: STARTED
[03:00:03.566] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:23:0) after 2m54s
[03:02:57.705] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:23:0)
[03:02:57.742] Build status: STARTED
[03:02:57.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:24:0) after 9m5s
[03:12:02.784] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:24:0)
[03:12:02.813] Build status: STARTED
[03:12:02.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:25:0) after 6m8s
[03:18:11.055] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:25:0)
[03:18:11.086] Build status: STARTED
[03:18:11.086] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:26:0) after 8m22s
[03:26:33.233] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:26:0)
[03:26:33.263] Build status: STARTED
[03:26:33.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:27:0) after 9m35s
[03:36:08.264] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:27:0)
[03:36:08.292] Build status: STARTED
[03:36:08.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:28:0) after 7m35s
[03:43:43.315] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:28:0)
[03:43:43.345] Build status: STARTED
[03:43:43.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:29:0) after 2m11s
[03:45:54.409] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:29:0)
[03:45:54.437] Build status: STARTED
[03:45:54.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:30:0) after 5m32s
[03:51:26.461] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:30:0)
[03:51:26.495] Build status: STARTED
[03:51:26.495] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:31:0) after 3m24s
[03:54:50.598] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:31:0)
[03:54:50.629] Build status: STARTED
[03:54:50.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:32:0) after 6m0s
[04:00:50.844] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:32:0)
[04:00:50.876] Build status: STARTED
[04:00:50.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:33:0) after 5m59s
[04:06:49.962] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:33:0)
[04:06:49.988] Build status: STARTED
[04:06:49.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:34:0) after 4m50s
[04:11:40.027] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:34:0)
[04:11:40.056] Build status: STARTED
[04:11:40.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:35:0) after 4m55s
[04:16:35.151] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:35:0)
[04:16:35.182] Build status: STARTED
[04:16:35.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:36:0) after 3m45s
[04:20:20.204] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:36:0)
[04:20:20.232] Build status: STARTED
[04:20:20.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:37:0) after 8m8s
[04:28:28.258] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:37:0)
[04:28:28.291] Build status: STARTED
[04:28:28.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:38:0) after 4m34s
[04:33:02.311] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:38:0)
[04:33:02.336] Build status: STARTED
[04:33:02.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:39:0) after 9m49s
[04:42:51.377] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:39:0)
[04:42:51.508] Build status: STARTED
[04:42:51.508] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:40:0) after 5m36s
[04:48:27.532] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:40:0)
[04:48:27.562] Build status: STARTED
[04:48:27.562] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:41:0) after 7m18s
[04:55:45.582] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:41:0)
[04:55:45.608] Build status: STARTED
[04:55:45.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:42:0) after 2m39s
[04:58:24.729] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:42:0)
[04:58:24.760] Build status: STARTED
[04:58:24.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:43:0) after 9m15s
[05:07:40.011] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:43:0)
[05:07:40.037] Build status: STARTED
[05:07:40.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:44:0) after 6m0s
[05:13:40.063] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:44:0)
[05:13:40.091] Build status: STARTED
[05:13:40.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:45:0) after 7m45s
[05:21:25.165] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:45:0)
[05:21:25.197] Build status: STARTED
[05:21:25.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:46:0) after 6m26s
[05:27:51.219] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:46:0)
[05:27:51.406] Build status: STARTED
[05:27:51.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:47:0) after 4m29s
[05:32:20.479] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:47:0)
[05:32:20.518] Build status: STARTED
[05:32:20.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:48:0) after 2m16s
[05:34:36.609] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:48:0)
[05:34:36.637] Build status: STARTED
[05:34:36.637] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:49:0) after 1m19s
[05:35:55.659] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:49:0)
[05:35:55.685] Build status: STARTED
[05:35:55.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:50:0) after 6m1s
[05:41:57.012] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:50:0)
[05:41:57.046] Build status: STARTED
[05:41:57.046] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:51:0) after 1m41s
[05:43:38.110] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:51:0)
[05:43:38.140] Build status: STARTED
[05:43:38.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:52:0) after 1m11s
[05:44:49.238] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:52:0)
[05:44:49.272] Build status: STARTED
[05:44:49.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:53:0) after 8m28s
[05:53:17.494] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:53:0)
[05:53:17.524] Build status: STARTED
[05:53:17.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:54:0) after 2m24s
[05:55:41.771] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:54:0)
[05:55:41.801] Build status: STARTED
[05:55:41.801] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:55:0) after 6m54s
[06:02:35.901] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:55:0)
[06:02:35.934] Build status: STARTED
[06:02:35.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid (dbg):9014721902487727056:56:0) after 9m40s
[06:10:50.967] Received PubSub notification, asking Buildbucket for the build status
[06:10:50.994] Build:
{
"id": "8849291840553921232",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid (dbg)"
},
"number": 12548,
"createdBy": "project:chromium",
"createTime": "2021-04-22T01:24:35.488078Z",
"startTime": "2021-04-22T01:24:37.370928Z",
"endTime": "2021-04-22T06:10:50.174572Z",
"updateTime": "2021-04-22T06:10:50.767199Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02945f6d0c08adf6aa3e53e35a9045a35f0cdddd",
"ref": "refs/heads/master"
}
}
}
[06:10:50.994] Invocation finished in 4h46m16.733798044s with status SUCCEEDED