[17:21:27.485] New invocation is queued and will start shortly
[17:21:29.061] Starting the invocation (attempt 1)
[17:21:29.108] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:21:29.108] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:21:29.109] Buildbucket request:
{
"bucket": "luci.pdfium.ci",
"client_operation_id": "9031875123991141264",
"parameters_json": "{\"builder_name\":\"win_xfa_asan\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://pdfium.googlesource.com/pdfium.git\",\"revision\":\"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"},\"id\":\"https://pdfium.googlesource.com/pdfium.git/+/refs/heads/master@7dd9dbd6dd4959a568e7701da19871f859f8dce2\",\"title\":\"7dd9dbd6dd4959a568e7701da19871f859f8dce2\",\"url\":\"https://pdfium.googlesource.com/pdfium.git/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://pdfium.googlesource.com/pdfium.git\",\"revision\":\"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win_xfa_asan",
"scheduler_invocation_id:9031875123991141264",
"scheduler_job_id:pdfium/win_xfa_asan",
"user_agent:luci-scheduler",
"buildset:commit/git/7dd9dbd6dd4959a568e7701da19871f859f8dce2",
"buildset:commit/gitiles/pdfium.googlesource.com/pdfium/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2",
"gitiles_ref:refs/heads/master"
]
}
[17:21:29.969] Buildbucket response:
{
"build": {
"bucket": "luci.pdfium.ci",
"canary_preference": "PROD",
"created_by": "project:pdfium",
"created_ts": "1602696089262534",
"id": "8866445061464367328",
"parameters_json": "{\"builder_name\": \"win_xfa_asan\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://pdfium.googlesource.com/pdfium.git\", \"revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}, \"id\": \"https://pdfium.googlesource.com/pdfium.git/+/refs/heads/master@7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"title\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"url\": \"https://pdfium.googlesource.com/pdfium.git/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://pdfium.googlesource.com/pdfium.git\", \"revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}}",
"project": "pdfium",
"result_details_json": "{\"properties\": {}}",
"service_account": "pdfium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602696089839094",
"tags": [
"build_address:luci.pdfium.ci/win_xfa_asan/4814",
"builder:win_xfa_asan",
"buildset:commit/git/7dd9dbd6dd4959a568e7701da19871f859f8dce2",
"buildset:commit/gitiles/pdfium.googlesource.com/pdfium/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031875123991141264",
"scheduler_job_id:pdfium/win_xfa_asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/pdfium/buildbucket/cr-buildbucket.appspot.com/8866445061464367328/+/annotations",
"swarming_tag:luci_project:pdfium",
"swarming_tag:recipe_name:pdfium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1602696089839228",
"url": "https://ci.chromium.org/b/8866445061464367328",
"utcnow_ts": "1602696089958617"
}
}
[17:21:29.969] Task URL: https://ci.chromium.org/b/8866445061464367328
[17:21:29.969] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:2:0) after 1m0s
[17:22:29.995] Received PubSub notification, asking Buildbucket for the build status
[17:22:30.113] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:30.428] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:2:0)
[17:22:30.428] Timer tick, asking Buildbucket for the build status
[17:22:30.592] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:30.592] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:4:0) after 1m0s
[17:23:30.700] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:4:0)
[17:23:30.700] Timer tick, asking Buildbucket for the build status
[17:23:30.784] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:30.784] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:5:0) after 1m0s
[17:24:30.892] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:5:0)
[17:24:30.892] Timer tick, asking Buildbucket for the build status
[17:24:31.174] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:31.174] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:6:0) after 1m0s
[17:25:31.583] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:6:0)
[17:25:31.583] Timer tick, asking Buildbucket for the build status
[17:25:32.012] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:32.012] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:7:0) after 1m0s
[17:26:32.236] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:7:0)
[17:26:32.236] Timer tick, asking Buildbucket for the build status
[17:26:32.315] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:32.315] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:8:0) after 1m0s
[17:27:32.718] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:8:0)
[17:27:32.718] Timer tick, asking Buildbucket for the build status
[17:27:32.995] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:32.995] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:9:0) after 1m0s
[17:28:33.061] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:9:0)
[17:28:33.061] Timer tick, asking Buildbucket for the build status
[17:28:33.179] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:33.179] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:10:0) after 1m0s
[17:29:33.198] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:10:0)
[17:29:33.198] Timer tick, asking Buildbucket for the build status
[17:29:33.541] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:33.541] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:11:0) after 1m0s
[17:30:33.597] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:11:0)
[17:30:33.597] Timer tick, asking Buildbucket for the build status
[17:30:33.760] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:33.760] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:12:0) after 1m0s
[17:31:33.814] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:12:0)
[17:31:33.814] Timer tick, asking Buildbucket for the build status
[17:31:35.880] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:35.880] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:13:0) after 1m0s
[17:32:35.938] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:13:0)
[17:32:35.938] Timer tick, asking Buildbucket for the build status
[17:32:36.029] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:36.029] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:14:0) after 1m0s
[17:33:36.032] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:14:0)
[17:33:36.032] Timer tick, asking Buildbucket for the build status
[17:33:36.107] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:36.107] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:15:0) after 1m0s
[17:34:36.188] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:15:0)
[17:34:36.188] Timer tick, asking Buildbucket for the build status
[17:34:36.408] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:36.408] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:16:0) after 1m0s
[17:35:36.428] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:16:0)
[17:35:36.428] Timer tick, asking Buildbucket for the build status
[17:35:36.507] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:36.507] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:17:0) after 1m0s
[17:36:36.556] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:17:0)
[17:36:36.556] Timer tick, asking Buildbucket for the build status
[17:36:37.200] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:37.200] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:18:0) after 1m0s
[17:37:37.319] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:18:0)
[17:37:37.319] Timer tick, asking Buildbucket for the build status
[17:37:40.045] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:40.045] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:19:0) after 1m0s
[17:38:40.059] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:19:0)
[17:38:40.059] Timer tick, asking Buildbucket for the build status
[17:38:40.212] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:40.212] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:20:0) after 1m0s
[17:39:40.228] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:20:0)
[17:39:40.228] Timer tick, asking Buildbucket for the build status
[17:39:40.549] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:40.549] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:21:0) after 1m0s
[17:40:40.549] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:21:0)
[17:40:40.549] Timer tick, asking Buildbucket for the build status
[17:40:40.639] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:40.639] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:22:0) after 1m0s
[17:41:40.738] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:22:0)
[17:41:40.738] Timer tick, asking Buildbucket for the build status
[17:41:40.830] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:40.830] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:23:0) after 1m0s
[17:42:40.832] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:23:0)
[17:42:40.832] Timer tick, asking Buildbucket for the build status
[17:42:40.937] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:40.937] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:24:0) after 1m0s
[17:43:41.010] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:24:0)
[17:43:41.010] Timer tick, asking Buildbucket for the build status
[17:43:41.138] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:41.138] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:25:0) after 1m0s
[17:44:41.195] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:25:0)
[17:44:41.195] Timer tick, asking Buildbucket for the build status
[17:44:41.429] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:41.430] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:26:0) after 1m0s
[17:45:41.455] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:26:0)
[17:45:41.455] Timer tick, asking Buildbucket for the build status
[17:45:41.536] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:41.536] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:27:0) after 1m0s
[17:46:41.543] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:27:0)
[17:46:41.543] Timer tick, asking Buildbucket for the build status
[17:46:41.670] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:41.670] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:28:0) after 1m0s
[17:47:41.712] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:28:0)
[17:47:41.712] Timer tick, asking Buildbucket for the build status
[17:47:41.784] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:41.784] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:29:0) after 1m0s
[17:48:41.798] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:29:0)
[17:48:41.798] Timer tick, asking Buildbucket for the build status
[17:48:41.869] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:41.869] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:30:0) after 1m0s
[17:49:41.980] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:30:0)
[17:49:41.980] Timer tick, asking Buildbucket for the build status
[17:49:42.944] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:42.944] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:31:0) after 1m0s
[17:50:42.958] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:31:0)
[17:50:42.958] Timer tick, asking Buildbucket for the build status
[17:50:43.046] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:43.046] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:32:0) after 1m0s
[17:51:43.075] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:32:0)
[17:51:43.075] Timer tick, asking Buildbucket for the build status
[17:51:43.194] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:43.194] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:33:0) after 1m0s
[17:52:43.249] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:33:0)
[17:52:43.249] Timer tick, asking Buildbucket for the build status
[17:52:43.333] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:43.333] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:34:0) after 1m0s
[17:53:43.354] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:34:0)
[17:53:43.354] Timer tick, asking Buildbucket for the build status
[17:53:43.524] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:43.524] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:35:0) after 1m0s
[17:54:43.551] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:35:0)
[17:54:43.551] Timer tick, asking Buildbucket for the build status
[17:54:43.655] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:43.655] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:36:0) after 1m0s
[17:55:43.741] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:36:0)
[17:55:43.741] Timer tick, asking Buildbucket for the build status
[17:55:43.796] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:43.796] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:37:0) after 1m0s
[17:56:43.927] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:37:0)
[17:56:43.927] Timer tick, asking Buildbucket for the build status
[17:56:44.073] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:44.073] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:38:0) after 1m0s
[17:57:44.202] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:38:0)
[17:57:44.202] Timer tick, asking Buildbucket for the build status
[17:57:44.278] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:44.278] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:39:0) after 1m0s
[17:58:44.327] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:39:0)
[17:58:44.327] Timer tick, asking Buildbucket for the build status
[17:58:44.393] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:44.393] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:40:0) after 1m0s
[17:59:44.424] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:40:0)
[17:59:44.424] Timer tick, asking Buildbucket for the build status
[17:59:44.696] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:44.696] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:41:0) after 1m0s
[18:00:44.772] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:41:0)
[18:00:44.772] Timer tick, asking Buildbucket for the build status
[18:00:44.901] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:44.901] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:42:0) after 1m0s
[18:01:44.937] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:42:0)
[18:01:44.937] Timer tick, asking Buildbucket for the build status
[18:01:45.371] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:45.371] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:43:0) after 1m0s
[18:02:45.408] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:43:0)
[18:02:45.409] Timer tick, asking Buildbucket for the build status
[18:02:45.682] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:45.682] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:44:0) after 1m0s
[18:03:45.707] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:44:0)
[18:03:45.707] Timer tick, asking Buildbucket for the build status
[18:03:45.780] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:45.780] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:45:0) after 1m0s
[18:04:45.857] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:45:0)
[18:04:45.857] Timer tick, asking Buildbucket for the build status
[18:04:46.008] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:46.008] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:46:0) after 1m0s
[18:05:46.080] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:46:0)
[18:05:46.080] Timer tick, asking Buildbucket for the build status
[18:05:46.276] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:46.276] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:47:0) after 1m0s
[18:06:46.311] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:47:0)
[18:06:46.311] Timer tick, asking Buildbucket for the build status
[18:06:46.807] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:46.807] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:48:0) after 1m0s
[18:07:46.830] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:48:0)
[18:07:46.830] Timer tick, asking Buildbucket for the build status
[18:07:47.036] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:47.036] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:49:0) after 1m0s
[18:08:47.056] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:49:0)
[18:08:47.056] Timer tick, asking Buildbucket for the build status
[18:08:47.196] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:47.196] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:50:0) after 1m0s
[18:09:47.224] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:50:0)
[18:09:47.224] Timer tick, asking Buildbucket for the build status
[18:09:47.410] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:47.410] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:51:0) after 1m0s
[18:10:47.449] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:51:0)
[18:10:47.449] Timer tick, asking Buildbucket for the build status
[18:10:47.690] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:47.691] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:52:0) after 1m0s
[18:11:47.839] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:52:0)
[18:11:47.839] Timer tick, asking Buildbucket for the build status
[18:11:48.078] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:48.078] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:53:0) after 1m0s
[18:12:48.168] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:53:0)
[18:12:48.168] Timer tick, asking Buildbucket for the build status
[18:12:48.298] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:48.298] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:54:0) after 1m0s
[18:13:48.462] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:54:0)
[18:13:48.462] Timer tick, asking Buildbucket for the build status
[18:13:48.536] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:48.536] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:55:0) after 1m0s
[18:14:48.629] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:55:0)
[18:14:48.629] Timer tick, asking Buildbucket for the build status
[18:14:48.897] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:48.897] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:56:0) after 1m0s
[18:15:48.937] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:56:0)
[18:15:48.937] Timer tick, asking Buildbucket for the build status
[18:15:49.229] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:49.229] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:57:0) after 1m0s
[18:16:49.376] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:57:0)
[18:16:49.376] Timer tick, asking Buildbucket for the build status
[18:16:50.391] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:50.391] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:58:0) after 1m0s
[18:17:50.408] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:58:0)
[18:17:50.408] Timer tick, asking Buildbucket for the build status
[18:17:50.556] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:50.556] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:59:0) after 1m0s
[18:18:50.596] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:59:0)
[18:18:50.596] Timer tick, asking Buildbucket for the build status
[18:18:51.472] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:51.472] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:60:0) after 1m0s
[18:19:51.519] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:60:0)
[18:19:51.519] Timer tick, asking Buildbucket for the build status
[18:19:51.592] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:51.592] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:61:0) after 1m0s
[18:20:51.664] Handling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:61:0)
[18:20:51.664] Timer tick, asking Buildbucket for the build status
[18:20:52.200] Build 8866445061464367328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:52.200] Scheduling timer "check-buildbucket-build-status" (pdfium/win_xfa_asan:9031875123991141264:62:0) after 1m0s
[18:21:32.916] Received PubSub notification, asking Buildbucket for the build status
[18:21:32.982] Build 8866445061464367328: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:21:32.982] Buildbucket build:
{
"build": {
"bucket": "luci.pdfium.ci",
"canary_preference": "PROD",
"completed_ts": "1602699687706598",
"created_by": "project:pdfium",
"created_ts": "1602696089262534",
"id": "8866445061464367328",
"parameters_json": "{\"builder_name\": \"win_xfa_asan\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://pdfium.googlesource.com/pdfium.git\", \"revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}, \"id\": \"https://pdfium.googlesource.com/pdfium.git/+/refs/heads/master@7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"title\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"url\": \"https://pdfium.googlesource.com/pdfium.git/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://pdfium.googlesource.com/pdfium.git\", \"revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}}",
"project": "pdfium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://pdfium.googlesource.com/pdfium.git\", \"revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}, \"id\": \"https://pdfium.googlesource.com/pdfium.git/+/refs/heads/master@7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"title\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"url\": \"https://pdfium.googlesource.com/pdfium.git/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2\"}]}, \"bot_id\": \"luci-flex-ci-win10-6-vqhm\", \"branch\": \"refs/heads/master\", \"builder_group\": \"client.pdfium\", \"buildername\": \"win_xfa_asan\", \"buildnumber\": 4814, \"clang\": true, \"got_revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"memory_tool\": \"asan\", \"path_config\": \"generic\", \"recipe\": \"pdfium\", \"rel\": true, \"repository\": \"https://pdfium.googlesource.com/pdfium.git\", \"revision\": \"7dd9dbd6dd4959a568e7701da19871f859f8dce2\", \"xfa\": true}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-flex-ci-win10-6-vqhm\"], \"image\": [\"chrome-win10-1703-20101200-4a97c2da996\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.flex.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5570-fa4adf0\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}}",
"service_account": "pdfium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1602696149072232",
"status": "COMPLETED",
"status_changed_ts": "1602699692317550",
"tags": [
"build_address:luci.pdfium.ci/win_xfa_asan/4814",
"builder:win_xfa_asan",
"buildset:commit/git/7dd9dbd6dd4959a568e7701da19871f859f8dce2",
"buildset:commit/gitiles/pdfium.googlesource.com/pdfium/+/7dd9dbd6dd4959a568e7701da19871f859f8dce2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031875123991141264",
"scheduler_job_id:pdfium/win_xfa_asan",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/pdfium/buildbucket/cr-buildbucket.appspot.com/8866445061464367328/+/annotations",
"swarming_tag:luci_project:pdfium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:pdfium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4f40f31bd366f910",
"user_agent:luci-scheduler"
],
"updated_ts": "1602699692425735",
"url": "https://ci.chromium.org/b/8866445061464367328",
"utcnow_ts": "1602699692972091"
}
}
[18:21:32.982] Invocation finished in 1h0m5.512782293s with status SUCCEEDED