deploy error - token exchange failed

Visto 815 veces

Saltar al primer mensaje no leído

Laurent Parodi

no leída,

4 oct 2019, 10:56:234/10/19

a Google App Engine

Hi all,

I discover app engine using the tutorials, starting with the "hello world" node one. All deployments fail with a cryptic token exchange error:

starting build "e47858c0-6cbc-4c73-bbbc-bf6b78cf4f8f"

FETCHSOURCE
BUILD
Starting Step #0 - "fetcher"
Step #0 - "fetcher": Already have image (with digest): gcr.io/cloud-builders/gcs-fetcher
Step #0 - "fetcher": Fetching manifest gs://staging.lp-sandbox-254814.appspot.com/ae/5b39e792-2ec1-481e-86e3-ebd979ffb6e4/manifest.json.
Step #0 - "fetcher": Processing 6 files.
Step #0 - "fetcher": ******************************************************
Step #0 - "fetcher": Status: SUCCESS
Step #0 - "fetcher": Started: 2019-10-04T12:25:48Z
Step #0 - "fetcher": Completed: 2019-10-04T12:25:52Z
Step #0 - "fetcher": Requested workers: 200
Step #0 - "fetcher": Actual workers: 6
Step #0 - "fetcher": Total files: 6
Step #0 - "fetcher": Total retries: 1
Step #0 - "fetcher": GCS timeouts: 1
Step #0 - "fetcher": MiB downloaded: 0.00 MiB
Step #0 - "fetcher": MiB/s throughput: 0.00 MiB/s
Step #0 - "fetcher": Time for manifest: 649.10 ms
Step #0 - "fetcher": Total time: 3.88 s
Step #0 - "fetcher": ******************************************************
Finished Step #0 - "fetcher"
Starting Step #1 - "builder"
Step #1 - "builder": Pulling image: gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00
Step #1 - "builder": nodejs10_20190827_10_16_2_RC00: Pulling from gae-runtimes/nodejs10_app_builder
Step #1 - "builder": Digest: sha256:c780ff233bda5bf40cb5c19ee8476b2965015f882c62d5cbd4bd3f28a30ec3eb
Step #1 - "builder": Status: Downloaded newer image for gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00
Step #1 - "builder": gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00
Step #1 - "builder": 2019/10/04 12:25:56 Starting commonbuild with args [commonbuild --runtime=nodejs10 --entrypoint= --src=/workspace --config-file=/.gaeconfig/app_start.json -- ftl.par --name=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:5b39e792-2ec1-481e-86e3-ebd979ffb6e4 --directory=/workspace --destination=/srv --cache-repository=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d --cache --additional-directory=/.gaeconfig --builder-output-path="" --base=eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00].
Step #1 - "builder": 2019/10/04 12:25:56 No start command generator found for nodejs10, using default app start command "serve".
Step #1 - "builder": 2019/10/04 12:25:56 Writing configuration file "/.gaeconfig/app_start.json".
Step #1 - "builder": 2019/10/04 12:25:56 Invoking build command "ftl.par --name=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:5b39e792-2ec1-481e-86e3-ebd979ffb6e4 --directory=/workspace --destination=/srv --cache-repository=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d --cache --additional-directory=/.gaeconfig --builder-output-path=\"\" --base=eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00".
Step #1 - "builder": INFO FTL version node-v0.17.0
Step #1 - "builder": INFO Beginning FTL build for node
Step #1 - "builder": INFO FTL arg passed: exposed_ports None
Step #1 - "builder": INFO FTL arg passed: cache_repository eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d
Step #1 - "builder": INFO FTL arg passed: tar_base_image_path None
Step #1 - "builder": INFO FTL arg passed: export_cache_stats False
Step #1 - "builder": INFO FTL arg passed: builder_output_path ""
Step #1 - "builder": INFO FTL arg passed: name eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:5b39e792-2ec1-481e-86e3-ebd979ffb6e4
Step #1 - "builder": INFO FTL arg passed: ttl 168
Step #1 - "builder": INFO FTL arg passed: global_cache False
Step #1 - "builder": INFO FTL arg passed: cache True
Step #1 - "builder": INFO FTL arg passed: upload True
Step #1 - "builder": INFO FTL arg passed: sh_c_prefix False
Step #1 - "builder": INFO FTL arg passed: fail_on_error True
Step #1 - "builder": INFO FTL arg passed: base eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00
Step #1 - "builder": INFO FTL arg passed: output_path None
Step #1 - "builder": INFO FTL arg passed: cache_key_version v0.17.0
Step #1 - "builder": INFO FTL arg passed: cache_salt
Step #1 - "builder": INFO FTL arg passed: directory /workspace
Step #1 - "builder": INFO FTL arg passed: entrypoint None
Step #1 - "builder": INFO FTL arg passed: additional_directory /.gaeconfig
Step #1 - "builder": INFO FTL arg passed: destination_path /srv
Step #1 - "builder": INFO FTL arg passed: verbosity NOTSET
Step #1 - "builder": INFO starting: full build
Step #1 - "builder": INFO starting: builder initialization
Step #1 - "builder": INFO Loading Docker credentials for repository 'eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00'
Step #1 - "builder": INFO Loading Docker credentials for repository 'eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:5b39e792-2ec1-481e-86e3-ebd979ffb6e4'
Step #1 - "builder": INFO Found neither yarn.lock or package-lock.json,generating package-lock.json from package.json
Step #1 - "builder": INFO starting: gen_package_lock
Step #1 - "builder": INFO gen_package_lock npm install --package-lock-only
Step #1 - "builder": INFO `gen_package_lock` stdout:
Step #1 - "builder": added 159 packages and audited 333 packages in 5.665s
Step #1 - "builder": found 0 vulnerabilities
Step #1 - "builder":
Step #1 - "builder":
Step #1 - "builder": INFO `gen_package_lock` had stderr output:
Step #1 - "builder": npm WARN deprecated super...@3.8.3: Please note that v5.0.1+ of superagent removes User-Agent header by default, therefore you may need to add it yourself (e.g. GitHub blocks requests without a User-Agent header). This notice will go away with v5.0.2+ once it is released.
Step #1 - "builder": npm notice created a lockfile as package-lock.json. You should commit this file.
Step #1 - "builder":
Step #1 - "builder": INFO gen_package_lock took 7 seconds
Step #1 - "builder": INFO builder initialization took 7 seconds
Step #1 - "builder": INFO starting: build process for FTL image
Step #1 - "builder": INFO starting: rm_node_modules
Step #1 - "builder": INFO rm_node_modules rm -rf /workspace/node_modules
Step #1 - "builder": INFO `rm_node_modules` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO rm_node_modules took 0 seconds
Step #1 - "builder": INFO using descriptor:package-lock.json
Step #1 - "builder": INFO using descriptor:package.json
Step #1 - "builder": INFO starting: checking_cached_packages_json_layer
Step #1 - "builder": DEBUG Checking cache for cache_key bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO No cached base image found for entry: eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633.
Step #1 - "builder": INFO Cache miss on local cache for eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO No cached dependency layer for bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO [CACHE][MISS] v0.17.0:NODE->bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO checking_cached_packages_json_layer took 0 seconds
Step #1 - "builder": INFO starting: building_packages_json_layer
Step #1 - "builder": INFO starting: npm_install
Step #1 - "builder": INFO npm_install npm install --production
Step #1 - "builder": INFO `npm_install` stdout:
Step #1 - "builder": added 50 packages from 37 contributors and audited 333 packages in 3.386s
Step #1 - "builder": found 0 vulnerabilities
Step #1 - "builder":
Step #1 - "builder":
Step #1 - "builder": INFO npm_install took 4 seconds
Step #1 - "builder": INFO starting: tar_runtime_package
Step #1 - "builder": INFO tar_runtime_package tar -pcf /tmp/tmp9ogASw.tar --hard-dereference --transform flags=r;s,^,/srv/node_modules/, --exclude *.pyc .
Step #1 - "builder": INFO `tar_runtime_package` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO tar_runtime_package took 0 seconds
Step #1 - "builder": INFO starting: gzip_tar_runtime_package
Step #1 - "builder": INFO gzip_tar_runtime_package gzip /tmp/tmp9ogASw.tar -1
Step #1 - "builder": INFO `gzip_tar_runtime_package` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO gzip_tar_runtime_package took 0 seconds
Step #1 - "builder": INFO starting: rm_node_modules
Step #1 - "builder": INFO rm_node_modules rm -rf /workspace/node_modules
Step #1 - "builder": INFO `rm_node_modules` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO rm_node_modules took 0 seconds
Step #1 - "builder": INFO building_packages_json_layer took 4 seconds
Step #1 - "builder": INFO starting: uploading_packages_json_layer
Step #1 - "builder": INFO uploading_packages_json_layer took 0 seconds
Step #1 - "builder": INFO build process for FTL image took 5 seconds
Step #1 - "builder": INFO full build took 13 seconds
Step #1 - "builder": Traceback (most recent call last):
Step #1 - "builder": File "/usr/lib/python2.7/runpy.py", line 174, in _run_module_as_main
Step #1 - "builder": "__main__", fname, loader, pkg_name)
Step #1 - "builder": File "/usr/lib/python2.7/runpy.py", line 72, in _run_code
Step #1 - "builder": exec code in run_globals
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__.py", line 65, in <module>
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__.py", line 54, in main
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/node/builder.py", line 81, in Build
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/node/layer_builder.py", line 85, in BuildLayer
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/common/cache.py", line 211, in Set
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_session_.py", line 71, in __init__
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 194, in __init__
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 288, in _Refresh
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 140, in _CheckState
Step #1 - "builder": containerregistry.client.v2_2.docker_http_.BadStateException: Bad status during token exchange: 400
Step #1 - "builder": {"errors":[{"code":"UNKNOWN","message":"Token exchange failed for project 'lp-sandbox-254814'. Bad Request."}]}
Step #1 - "builder": 2019/10/04 12:26:10 INFO FTL version node-v0.17.0
Step #1 - "builder": INFO Beginning FTL build for node
Step #1 - "builder": INFO FTL arg passed: exposed_ports None
Step #1 - "builder": INFO FTL arg passed: cache_repository eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d
Step #1 - "builder": INFO FTL arg passed: tar_base_image_path None
Step #1 - "builder": INFO FTL arg passed: export_cache_stats False
Step #1 - "builder": INFO FTL arg passed: builder_output_path ""
Step #1 - "builder": INFO FTL arg passed: name eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:5b39e792-2ec1-481e-86e3-ebd979ffb6e4
Step #1 - "builder": INFO FTL arg passed: ttl 168
Step #1 - "builder": INFO FTL arg passed: global_cache False
Step #1 - "builder": INFO FTL arg passed: cache True
Step #1 - "builder": INFO FTL arg passed: upload True
Step #1 - "builder": INFO FTL arg passed: sh_c_prefix False
Step #1 - "builder": INFO FTL arg passed: fail_on_error True
Step #1 - "builder": INFO FTL arg passed: base eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00
Step #1 - "builder": INFO FTL arg passed: output_path None
Step #1 - "builder": INFO FTL arg passed: cache_key_version v0.17.0
Step #1 - "builder": INFO FTL arg passed: cache_salt
Step #1 - "builder": INFO FTL arg passed: directory /workspace
Step #1 - "builder": INFO FTL arg passed: entrypoint None
Step #1 - "builder": INFO FTL arg passed: additional_directory /.gaeconfig
Step #1 - "builder": INFO FTL arg passed: destination_path /srv
Step #1 - "builder": INFO FTL arg passed: verbosity NOTSET
Step #1 - "builder": INFO starting: full build
Step #1 - "builder": INFO starting: builder initialization
Step #1 - "builder": INFO Loading Docker credentials for repository 'eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00'
Step #1 - "builder": INFO Loading Docker credentials for repository 'eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:5b39e792-2ec1-481e-86e3-ebd979ffb6e4'
Step #1 - "builder": INFO Found neither yarn.lock or package-lock.json,generating package-lock.json from package.json
Step #1 - "builder": INFO starting: gen_package_lock
Step #1 - "builder": INFO gen_package_lock npm install --package-lock-only
Step #1 - "builder": INFO `gen_package_lock` stdout:
Step #1 - "builder": added 159 packages and audited 333 packages in 5.665s
Step #1 - "builder": found 0 vulnerabilities
Step #1 - "builder":
Step #1 - "builder":
Step #1 - "builder": INFO `gen_package_lock` had stderr output:
Step #1 - "builder": npm WARN deprecated super...@3.8.3: Please note that v5.0.1+ of superagent removes User-Agent header by default, therefore you may need to add it yourself (e.g. GitHub blocks requests without a User-Agent header). This notice will go away with v5.0.2+ once it is released.
Step #1 - "builder": npm notice created a lockfile as package-lock.json. You should commit this file.
Step #1 - "builder":
Step #1 - "builder": INFO gen_package_lock took 7 seconds
Step #1 - "builder": INFO builder initialization took 7 seconds
Step #1 - "builder": INFO starting: build process for FTL image
Step #1 - "builder": INFO starting: rm_node_modules
Step #1 - "builder": INFO rm_node_modules rm -rf /workspace/node_modules
Step #1 - "builder": INFO `rm_node_modules` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO rm_node_modules took 0 seconds
Step #1 - "builder": INFO using descriptor:package-lock.json
Step #1 - "builder": INFO using descriptor:package.json
Step #1 - "builder": INFO starting: checking_cached_packages_json_layer
Step #1 - "builder": DEBUG Checking cache for cache_key bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO No cached base image found for entry: eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633.
Step #1 - "builder": INFO Cache miss on local cache for eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO No cached dependency layer for bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO [CACHE][MISS] v0.17.0:NODE->bc967823696f8875025eaf1f5c60f87795d5e53158acf0f2dd510681eb7cb633
Step #1 - "builder": INFO checking_cached_packages_json_layer took 0 seconds
Step #1 - "builder": INFO starting: building_packages_json_layer
Step #1 - "builder": INFO starting: npm_install
Step #1 - "builder": INFO npm_install npm install --production
Step #1 - "builder": INFO `npm_install` stdout:
Step #1 - "builder": added 50 packages from 37 contributors and audited 333 packages in 3.386s
Step #1 - "builder": found 0 vulnerabilities
Step #1 - "builder":
Step #1 - "builder":
Step #1 - "builder": INFO npm_install took 4 seconds
Step #1 - "builder": INFO starting: tar_runtime_package
Step #1 - "builder": INFO tar_runtime_package tar -pcf /tmp/tmp9ogASw.tar --hard-dereference --transform flags=r;s,^,/srv/node_modules/, --exclude *.pyc .
Step #1 - "builder": INFO `tar_runtime_package` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO tar_runtime_package took 0 seconds
Step #1 - "builder": INFO starting: gzip_tar_runtime_package
Step #1 - "builder": INFO gzip_tar_runtime_package gzip /tmp/tmp9ogASw.tar -1
Step #1 - "builder": INFO `gzip_tar_runtime_package` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO gzip_tar_runtime_package took 0 seconds
Step #1 - "builder": INFO starting: rm_node_modules
Step #1 - "builder": INFO rm_node_modules rm -rf /workspace/node_modules
Step #1 - "builder": INFO `rm_node_modules` stdout:
Step #1 - "builder":
Step #1 - "builder": INFO rm_node_modules took 0 seconds
Step #1 - "builder": INFO building_packages_json_layer took 4 seconds
Step #1 - "builder": INFO starting: uploading_packages_json_layer
Step #1 - "builder": INFO uploading_packages_json_layer took 0 seconds
Step #1 - "builder": INFO build process for FTL image took 5 seconds
Step #1 - "builder": INFO full build took 13 seconds
Step #1 - "builder": Traceback (most recent call last):
Step #1 - "builder": File "/usr/lib/python2.7/runpy.py", line 174, in _run_module_as_main
Step #1 - "builder": "__main__", fname, loader, pkg_name)
Step #1 - "builder": File "/usr/lib/python2.7/runpy.py", line 72, in _run_code
Step #1 - "builder": exec code in run_globals
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__.py", line 65, in <module>
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__.py", line 54, in main
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/node/builder.py", line 81, in Build
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/node/layer_builder.py", line 85, in BuildLayer
Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/common/cache.py", line 211, in Set
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_session_.py", line 71, in __init__
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 194, in __init__
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 288, in _Refresh
Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 140, in _CheckState
Step #1 - "builder": containerregistry.client.v2_2.docker_http_.BadStateException: Bad status during token exchange: 400
Step #1 - "builder": {"errors":[{"code":"UNKNOWN","message":"Token exchange failed for project 'lp-sandbox-254814'. Bad Request."}]}
Finished Step #1 - "builder"
ERROR
ERROR: build step 1 "gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00" failed: exit status 1

I own the project, and I use my user account for the gcloud configuration.

Looked for the error on SO and here, not a single hit. What do I do wrong ?

Thanks

George (Cloud Platform Support)

no leída,

7 oct 2019, 14:10:127/10/19

a Google App Engine

Hello Laurent,

This seems to be an authentication issue. When installing Cloud SDK, have your run gcloud auth login to authorize gcloud to access the Cloud Platform with Google user credentials? What is the exact command you have run to get this output? You mention you employ your own user account for Cloud configuration. Does your user account enjoy all needed permissions? You may check Setting Up Access Control to make sure you have granted all needed permissions to your user account.

Laurent Parodi

no leída,

8 oct 2019, 10:47:448/10/19

a Google App Engine

Hello,

Thanks for the reply, I'm really lost.

Granted roles to my user account are:

App Engine Admin

Cloud Build Editor

Owner

Storage Admin

Then, I log on :

@nuc:~/nodejs-docs-samples/appengine/hello-world/standard$ gcloud auth login

Go to the following link in your browser:

https://accounts.google.com/o/oauth2/auth?code_challenge=rg4Kgf6ucDNPqHSyi3QyicMNO623iRBt4v0wMAMaLM0&prompt=select_account&code_challenge_method=S256&access_type=offline&redirect_uri=urn%3Aietf%3Awg%3Aoauth%3A2.0%3Aoob&response_type=code&client_id=32555940559.apps.googleusercontent.com&scope=https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fuserinfo.email+https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fcloud-platform+https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fappengine.admin+https%3A%2F%2Fwww.googleapis.com%2Fauth%2Fcompute+https%3A%2F%2Fwww.googleapis.com%2Fauth%2Faccounts.reauth

Enter verification code: 4/rwFIdCe_S7C63_y0P0tvGV8zK4zJRplLKFXhb0VX9XTVz24KqZsBADg

WARNING: `gcloud auth login` no longer writes application default credentials.

If you need to use ADC, see:

gcloud auth application-default --help

You are now logged in as [xxxxxx....@zzzzzzzzzz.com].

Your current project is [lp-sandbox-254814]. You can change this setting by running:

$ gcloud config set project PROJECT_ID

Then, I run the deploy:

l@nuc:~/nodejs-docs-samples/appengine/hello-world/standard$ gcloud app deploy

Services to deploy:

descriptor: [/home/l/nodejs-docs-samples/appengine/hello-world/standard/app.yaml]

source: [/home/l/nodejs-docs-samples/appengine/hello-world/standard]

target project: [lp-sandbox-254814]

target service: [default]

target version: [20191007t222332]

target url: [https://lp-sandbox-254814.appspot.com]

Do you want to continue (Y/n)? y

Beginning deployment of service [default]...

╔════════════════════════════════════════════════════════════╗

╠═ Uploading 0 files to Google Cloud Storage ═╣

╚════════════════════════════════════════════════════════════╝

File upload done.

Updating service [default]...failed.

ERROR: (gcloud.app.deploy) Error Response: [9] Cloud build bc837367-c520-48f4-a734-a5e86f33aa30 status: FAILURE.

Build error details: {"error":{"errorType":"BuildError","canonicalCode":"INVALID_ARGUMENT","errorId":"A815B8B7","errorMessage":"INFO FTL version node-v0.17.0\nINFO Beginning FTL build for node\nINFO FTL arg passed: exposed_ports None\nINFO FTL arg passed: cache_repository eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d\nINFO FTL arg passed: tar_base_image_path None\nINFO FTL arg passed: export_cache_stats False\nINFO FTL arg passed: builder_output_path \"\"\nINFO FTL arg passed: name eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:77c7b674-1333-4cdc-9a6d-5631eca997be\nINFO FTL arg passed: ttl 168\nINFO FTL arg passed: global_cache False\nINFO FTL arg passed: cache True\nINFO FTL arg passed: upload True\nINFO FTL arg passed: sh_c_prefix False\nINFO FTL arg passed: fail_on_error True\nINFO FTL arg passed: base eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00\nINFO FTL arg passed: output_path None\nINFO FTL arg passed: cache_key_version v0.17.0\nINFO FTL arg passed: cache_salt \nINFO FTL arg passed: directory /workspace\nINFO FTL arg passed: entrypoint None\nINFO FTL arg passed: additional_directory /.gaeconfig\nINFO FTL arg passed: destination_path /srv\nINFO FTL arg passed: verbosity NOTSET\nINFO starting: full build\nINFO starting: builder initialization\nINFO Loading Docker credentials for repository 'eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00'\nINFO Loading Docker credentials for repository 'eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:77c7b674-1333-4cdc-9a6d-5631eca997be'\nINFO builder initialization took 0 seconds\nINFO starting: build process for FTL image\nINFO starting: rm_node_modules\nINFO rm_node_modules rm -rf /workspace/node_modules\nINFO `rm_node_modules` stdout:\n\nINFO rm_node_modules took 0 seconds\nINFO using descriptor:package-lock.json\nINFO using descriptor:package.json\nINFO starting: checking_cached_packages_json_layer\nDEBUG Checking cache for cache_key 3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387\nINFO No cached base image found for entry: eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387.\nINFO Cache miss on local cache for eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387\nINFO No cached dependency layer for 3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387\nINFO [CACHE][MISS] v0.17.0:NODE-\u003e3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387\nINFO checking_cached_packages_json_layer took 0 seconds\nINFO starting: building_packages_json_layer\nINFO starting: npm_install\nINFO npm_install npm install --production\nINFO `npm_install` stdout:\nadded 140 packages from 84 contributors and audited 656 packages in 3.811s\nfound 0 vulnerabilities\n\n\nINFO npm_install took 5 seconds\nINFO starting: tar_runtime_package\nINFO tar_runtime_package tar -pcf /tmp/tmpfQbrju.tar --hard-dereference --transform flags=r;s,^,/srv/node_modules/, --exclude *.pyc .\nINFO `tar_runtime_package` stdout:\n\nINFO tar_runtime_package took 0 seconds\nINFO starting: gzip_tar_runtime_package\nINFO gzip_tar_runtime_package gzip /tmp/tmpfQbrju.tar -1\nINFO `gzip_tar_runtime_package` stdout:\n\nINFO gzip_tar_runtime_package took 0 seconds\nINFO starting: rm_node_modules\nINFO rm_node_modules rm -rf /workspace/node_modules\nINFO `rm_node_modules` stdout:\n\nINFO rm_node_modules took 0 seconds\nINFO building_packages_json_layer took 6 seconds\nINFO starting: uploading_packages_json_layer\nINFO uploading_packages_json_layer took 0 seconds\nINFO build process for FTL image took 6 seconds\nINFO full build took 7 seconds\nTraceback (most recent call last):\n File \"/usr/lib/python2.7/runpy.py\", line 174, in _run_module.

Check the build log for errors: https://console.cloud.google.com/gcr/builds/bc837367-c520-48f4-a734-a5e86f33aa30?project=115267158669

And the full Cloud Builder log is :

starting build "bc837367-c520-48f4-a734-a5e86f33aa30"

FETCHSOURCE

BUILD

Starting Step #0 - "fetcher"

Step #0 - "fetcher": Already have image (with digest): gcr.io/cloud-builders/gcs-fetcher

Step #0 - "fetcher": Fetching manifest gs://staging.lp-sandbox-254814.appspot.com/ae/77c7b674-1333-4cdc-9a6d-5631eca997be/manifest.json.

Step #0 - "fetcher": Processing 7 files.

Step #0 - "fetcher": ******************************************************

Step #0 - "fetcher": Status: SUCCESS

Step #0 - "fetcher": Started: 2019-10-07T20:23:46Z

Step #0 - "fetcher": Completed: 2019-10-07T20:23:47Z

Step #0 - "fetcher": Requested workers: 200

Step #0 - "fetcher": Actual workers: 7

Step #0 - "fetcher": Total files: 7

Step #0 - "fetcher": Total retries: 0

Step #0 - "fetcher": GCS timeouts: 0

Step #0 - "fetcher": MiB downloaded: 0.07 MiB

Step #0 - "fetcher": MiB/s throughput: 0.21 MiB/s

Step #0 - "fetcher": Time for manifest: 496.22 ms

Step #0 - "fetcher": Total time: 0.86 s

Step #0 - "fetcher": ******************************************************

Finished Step #0 - "fetcher"

Starting Step #1 - "builder"

Step #1 - "builder": Pulling image: gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00

Step #1 - "builder": nodejs10_20190827_10_16_2_RC00: Pulling from gae-runtimes/nodejs10_app_builder

Step #1 - "builder": Digest: sha256:c780ff233bda5bf40cb5c19ee8476b2965015f882c62d5cbd4bd3f28a30ec3eb

Step #1 - "builder": Status: Downloaded newer image for gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00

Step #1 - "builder": gcr.io/gae-runtimes/nodejs10_app_builder:nodejs10_20190827_10_16_2_RC00

Step #1 - "builder": 2019/10/07 20:23:49 Starting commonbuild with args [commonbuild --runtime=nodejs10 --entrypoint= --src=/workspace --config-file=/.gaeconfig/app_start.json -- ftl.par --name=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:77c7b674-1333-4cdc-9a6d-5631eca997be --directory=/workspace --destination=/srv --cache-repository=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d --cache --additional-directory=/.gaeconfig --builder-output-path="" --base=eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00].

Step #1 - "builder": 2019/10/07 20:23:49 No start command generator found for nodejs10, using default app start command "serve".

Step #1 - "builder": 2019/10/07 20:23:49 Writing configuration file "/.gaeconfig/app_start.json".

Step #1 - "builder": 2019/10/07 20:23:49 Invoking build command "ftl.par --name=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:77c7b674-1333-4cdc-9a6d-5631eca997be --directory=/workspace --destination=/srv --cache-repository=eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d --cache --additional-directory=/.gaeconfig --builder-output-path=\"\" --base=eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00".

Step #1 - "builder": INFO FTL version node-v0.17.0

Step #1 - "builder": INFO Beginning FTL build for node

Step #1 - "builder": INFO FTL arg passed: exposed_ports None

Step #1 - "builder": INFO FTL arg passed: cache_repository eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d

Step #1 - "builder": INFO FTL arg passed: tar_base_image_path None

Step #1 - "builder": INFO FTL arg passed: export_cache_stats False

Step #1 - "builder": INFO FTL arg passed: builder_output_path ""

Step #1 - "builder": INFO FTL arg passed: name eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:77c7b674-1333-4cdc-9a6d-5631eca997be

Step #1 - "builder": INFO FTL arg passed: ttl 168

Step #1 - "builder": INFO FTL arg passed: global_cache False

Step #1 - "builder": INFO FTL arg passed: cache True

Step #1 - "builder": INFO FTL arg passed: upload True

Step #1 - "builder": INFO FTL arg passed: sh_c_prefix False

Step #1 - "builder": INFO FTL arg passed: fail_on_error True

Step #1 - "builder": INFO FTL arg passed: base eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00

Step #1 - "builder": INFO FTL arg passed: output_path None

Step #1 - "builder": INFO FTL arg passed: cache_key_version v0.17.0

Step #1 - "builder": INFO FTL arg passed: cache_salt

Step #1 - "builder": INFO FTL arg passed: directory /workspace

Step #1 - "builder": INFO FTL arg passed: entrypoint None

Step #1 - "builder": INFO FTL arg passed: additional_directory /.gaeconfig

Step #1 - "builder": INFO FTL arg passed: destination_path /srv

Step #1 - "builder": INFO FTL arg passed: verbosity NOTSET

Step #1 - "builder": INFO starting: full build

Step #1 - "builder": INFO starting: builder initialization

Step #1 - "builder": INFO Loading Docker credentials for repository 'eu.gcr.io/gae-runtimes/nodejs10:nodejs10_20190827_10_16_2_RC00'

Step #1 - "builder": INFO Loading Docker credentials for repository 'eu.gcr.io/lp-sandbox-254814/app-engine-tmp/app/ttl-2h:77c7b674-1333-4cdc-9a6d-5631eca997be'

Step #1 - "builder": INFO builder initialization took 0 seconds

Step #1 - "builder": INFO starting: build process for FTL image

Step #1 - "builder": INFO starting: rm_node_modules

Step #1 - "builder": INFO rm_node_modules rm -rf /workspace/node_modules

Step #1 - "builder": INFO `rm_node_modules` stdout:

Step #1 - "builder":

Step #1 - "builder": INFO rm_node_modules took 0 seconds

Step #1 - "builder": INFO using descriptor:package-lock.json

Step #1 - "builder": INFO using descriptor:package.json

Step #1 - "builder": INFO starting: checking_cached_packages_json_layer

Step #1 - "builder": DEBUG Checking cache for cache_key 3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387

Step #1 - "builder": INFO No cached base image found for entry: eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387.

Step #1 - "builder": INFO Cache miss on local cache for eu.gcr.io/lp-sandbox-254814/app-engine-tmp/build-cache/ttl-7d/node-cache:3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387

Step #1 - "builder": INFO No cached dependency layer for 3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387

Step #1 - "builder": INFO [CACHE][MISS] v0.17.0:NODE->3b92fb27b83299cc2b8917f2ca0220beaabd42233b14dd6f9083dce71babb387

Step #1 - "builder": INFO checking_cached_packages_json_layer took 0 seconds

Step #1 - "builder": INFO starting: building_packages_json_layer

Step #1 - "builder": INFO starting: npm_install

Step #1 - "builder": INFO npm_install npm install --production

Step #1 - "builder": INFO `npm_install` stdout:

Step #1 - "builder": added 140 packages from 84 contributors and audited 656 packages in 3.811s

Step #1 - "builder": found 0 vulnerabilities

Step #1 - "builder":

Step #1 - "builder": INFO npm_install took 5 seconds

Step #1 - "builder": INFO starting: tar_runtime_package

Step #1 - "builder": INFO tar_runtime_package tar -pcf /tmp/tmpfQbrju.tar --hard-dereference --transform flags=r;s,^,/srv/node_modules/, --exclude *.pyc .

Step #1 - "builder": INFO `tar_runtime_package` stdout:

Step #1 - "builder":

Step #1 - "builder": INFO tar_runtime_package took 0 seconds

Step #1 - "builder": INFO starting: gzip_tar_runtime_package

Step #1 - "builder": INFO gzip_tar_runtime_package gzip /tmp/tmpfQbrju.tar -1

Step #1 - "builder": INFO `gzip_tar_runtime_package` stdout:

Step #1 - "builder":

Step #1 - "builder": INFO gzip_tar_runtime_package took 0 seconds

Step #1 - "builder": INFO starting: rm_node_modules

Step #1 - "builder": INFO rm_node_modules rm -rf /workspace/node_modules

Step #1 - "builder": INFO `rm_node_modules` stdout:

Step #1 - "builder":

Step #1 - "builder": INFO rm_node_modules took 0 seconds

Step #1 - "builder": INFO building_packages_json_layer took 6 seconds

Step #1 - "builder": INFO starting: uploading_packages_json_layer

Step #1 - "builder": INFO uploading_packages_json_layer took 0 seconds

Step #1 - "builder": INFO build process for FTL image took 6 seconds

Step #1 - "builder": INFO full build took 7 seconds

Step #1 - "builder": Traceback (most recent call last):

Step #1 - "builder": File "/usr/lib/python2.7/runpy.py", line 174, in _run_module_as_main

Step #1 - "builder": "__main__", fname, loader, pkg_name)

Step #1 - "builder": File "/usr/lib/python2.7/runpy.py", line 72, in _run_code

Step #1 - "builder": exec code in run_globals

Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__.py", line 65, in <module>

Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__.py", line 54, in main

Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/node/builder.py", line 81, in Build

Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/node/layer_builder.py", line 85, in BuildLayer

Step #1 - "builder": File "/usr/local/bin/ftl.par/__main__/ftl/common/cache.py", line 211, in Set

Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_session_.py", line 71, in __init__

Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 194, in __init__

Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 288, in _Refresh

Step #1 - "builder": File "/usr/local/bin/ftl.par/containerregistry/client/v2_2/docker_http_.py", line 140, in _CheckState

Step #1 - "builder": containerregistry.client.v2_2.docker_http_.BadStateException: Bad status during token exchange: 400

Step #1 - "builder": {"errors":[{"code":"UNKNOWN","message":"Token exchange failed for project 'lp-sandbox-254814'. Bad Request."}]}

Step #1 - "builder": 2019/10/07 20:23:57 INFO FTL version node-v0.17.0