search

armadillica / flamenco

Free and Open Source render manager.
https://flamenco.io
Other
264 stars 54 forks source link

Error 503 / 401 #232

Closed ArrowThief closed 2 years ago

ArrowThief commented 5 years ago

I recently decided to update from manager 2.1.1 to 2.5.1. I run into this error when I attempt to link to blender cloud as my server.

Once I select a manger and link It, it will either take me to a page that says "Unable to request a new authentication token from Flamenco Server: Token reset: Error 503 POSTing to https://cloud.blender.org/api/flamenco/managers/link/reset-token" or it takes me to the completed link page and I can restart the web setup. Then It usually throws an error stating that I am unauthorized, though sometimes It waits until its been restarted into normal (not setup) mode and then throws an error saying the server doesn't exist.

I'm running a fresh download from flamenco.io on windows 10.

sybrenstuvel commented 5 years ago

Please run with flamenco-manager.exe -debug and attach the debug output here.

ArrowThief commented 5 years ago

D:\Users\User\Desktop\flamenco-manager-2.5.1>dir Volume in drive D is Scratch Volume Serial Number is D6C3-A740

Directory of D:\Users\User\Desktop\flamenco-manager-2.5.1

09/17/2019 09:37 AM

. 09/17/2019 09:37 AM .. 09/17/2019 09:37 AM 14,980 CHANGELOG.md 09/17/2019 09:37 AM 5,307 flamenco-manager-example.yaml 09/17/2019 09:37 AM 15,617,024 flamenco-manager.exe 09/17/2019 09:37 AM jwtkeys 09/17/2019 09:37 AM 1,120 LICENSE.txt 09/17/2019 09:37 AM mongodb-windows 09/17/2019 09:37 AM 10,519 README.md 09/17/2019 09:37 AM static 09/17/2019 09:37 AM templates 5 File(s) 15,648,950 bytes 6 Dir(s) 1,258,853,699,584 bytes free

D:\Users\User\Desktop\flamenco-manager-2.5.1>flamenco-manager.exe -debug time="2019-09-17T09:38:19-05:00" level=info msg="Starting Flamenco Manager" version=v2.5.1 time="2019-09-17T09:38:19-05:00" level=debug msg="constructing variable lookup table" concreteAudiences="[workers users]" isWildcard="map[:true all:true]" time="2019-09-17T09:38:19-05:00" level=debug msg="handling variable" name=job_storage variable="{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]}" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=windows value="S:" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=windows value="S:" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-17T09:38:19-05:00" level=debug msg="handling variable" name=blender variable="{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]}" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=blender platform=linux value=/linux/path/to/blender time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=blender platform=linux value=/farm/path/to/blender time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-17T09:38:19-05:00" level=debug msg="handling variable" name=ffmpeg variable="{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]}" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-17T09:38:19-05:00" level=debug msg="handling variable" name=render variable="{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=render platform=linux value=/render time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=render platform=linux value=/render time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=render platform=windows value="R:" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=render platform=windows value="R:" time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=workers name=render platform=darwin value=/Volumes/render time="2019-09-17T09:38:19-05:00" level=debug msg="setting variable" audience=users name=render platform=darwin value=/Volumes/render time="2019-09-17T09:38:19-05:00" level=debug msg="constructed lookup table" lookup="map[users:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/linux/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]] workers:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/farm/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]]]" variables="map[blender:{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]} ffmpeg:{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]} job_storage:{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]} render:{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}]" time="2019-09-17T09:38:19-05:00" level=warning msg="Flamenco Manager configuration file not found, entering setup mode." time="2019-09-17T09:38:19-05:00" level=warning msg="Flamenco Manager not yet linked, entering setup mode." time="2019-09-17T09:38:19-05:00" level=info msg="Run mode" mode=production time="2019-09-17T09:38:19-05:00" level=warning msg="no Flamenco Server URL configured" time="2019-09-17T09:38:19-05:00" level=info msg="Entering setup mode" securityEnabled=true time="2019-09-17T09:38:19-05:00" level=debug msg="Flamenco Server URL is nil, linking is required." time="2019-09-17T09:38:19-05:00" level=warning msg="This Manager is not yet linked to a Server, disabling security." time="2019-09-17T09:38:19-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-17T09:38:19-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-17T09:38:19-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-17T09:38:19-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-17T09:38:19-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-17T09:38:19-05:00" level=info msg="Point your browser at any of these URLs:" time="2019-09-17T09:38:19-05:00" level=info msg=" - http://192.168.2.30:8080/setup" time="2019-09-17T09:38:19-05:00" level=debug msg="Found templates in current working directory" file_to_find=templates/layout.html time="2019-09-17T09:38:19-05:00" level=info msg="creating insecure server" listen=":8080" time="2019-09-17T09:38:19-05:00" level=debug msg="starting HTTP server" listen=":8080" time="2019-09-17T09:38:36-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-17T09:38:36-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-17T09:38:36-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-17T09:38:36-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-17T09:38:36-05:00" level=info msg="Own URL is not configured, choosing one based on the current request" time="2019-09-17T09:38:36-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-17T09:38:36-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-17T09:38:36-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-17T09:38:36-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-17T09:38:36-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-17T09:38:37-05:00" level=debug msg="Flamenco Server URL is nil, linking is required." time="2019-09-17T09:38:40-05:00" level=debug msg="Config file written to flamenco-manager.yaml~" time="2019-09-17T09:38:40-05:00" level=debug msg="Renaming flamenco-manager.yaml~ to flamenco-manager.yaml" time="2019-09-17T09:38:40-05:00" level=info msg="Saved configuration file to flamenco-manager.yaml" time="2019-09-17T09:38:40-05:00" level=info msg="Exchanging secret key with Flamenco Server https://cloud.blender.org/" time="2019-09-17T09:38:41-05:00" level=info msg="Key exchange with Flamenco Server succesful" time="2019-09-17T09:38:41-05:00" level=info msg="/setup/api/link-start?server=https%3A%2F%2Fcloud.blender.org%2F: going to link to https://cloud.blender.org/" time="2019-09-17T09:38:41-05:00" level=info msg="/setup/api/link-start?server=https%3A%2F%2Fcloud.blender.org%2F: redirecting user to https://cloud.blender.org/flamenco/managers/link/choose?hmac=48d21fbaab174d7830f22e97585391559af306b88d8bdc98030a35abc87942d4&identifier=5d80f072cbea371d192bff4f&return=http%3A%2F%2F192.168.2.30%3A8080%2Fsetup%2Flink-return" time="2019-09-17T09:38:46-05:00" level=info msg="Our Manager ID is 59c42e35f4885509b962c4f2" time="2019-09-17T09:38:46-05:00" level=info msg="Requesting new authentication token from Flamenco Server" time="2019-09-17T09:38:46-05:00" level=info msg="Requesting new auth token from Flamenco Server https://cloud.blender.org/" time="2019-09-17T09:38:47-05:00" level=info msg="Authentication token reset at Flamenco Server succesful" time="2019-09-17T09:38:47-05:00" level=info msg="Received new authentication token" time="2019-09-17T09:38:47-05:00" level=debug msg="Config file written to flamenco-manager.yaml~" time="2019-09-17T09:38:47-05:00" level=debug msg="Renaming flamenco-manager.yaml~ to flamenco-manager.yaml" time="2019-09-17T09:38:47-05:00" level=info msg="Saved configuration file to flamenco-manager.yaml" time="2019-09-17T09:38:49-05:00" level=warning msg="Restarting Flamenco Manager into setup mode by request of the web setup." remote_addr="192.168.2.30:62192" time="2019-09-17T09:38:49-05:00" level=info msg="Signal received, shutting down." signal=interrupt time="2019-09-17T09:38:49-05:00" level=info msg="Shutting down HTTP server" time="2019-09-17T09:38:49-05:00" level=info msg="Waiting for shutdown to complete." time="2019-09-17T09:38:49-05:00" level=warning msg="Shutdown complete, stopping process." time="2019-09-17T09:38:49-05:00" level=warning msg="Restarting Flamenco Server" time="2019-09-17T09:38:49-05:00" level=info msg="Started another Flamenco Manager" args="[-mode -debug -setup -kill-after-start 500]" exename="D:\Users\User\Desktop\flamenco-manager-2.5.1\flamenco-manager.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="waiting for our child process to kill us" time="2019-09-17T09:38:49-05:00" level=info msg="Starting Flamenco Manager" version=v2.5.1 time="2019-09-17T09:38:49-05:00" level=debug msg="Parent process terminated." pid=500 time="2019-09-17T09:38:49-05:00" level=debug msg="constructing variable lookup table" concreteAudiences="[workers users]" isWildcard="map[:true all:true]" time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=render variable="{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=render platform=linux value=/render time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=render platform=linux value=/render time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=render platform=windows value="R:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=render platform=windows value="R:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=render platform=darwin value=/Volumes/render time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=render platform=darwin value=/Volumes/render time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=job_storage variable="{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=windows value="S:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=windows value="S:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs

time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=blender variable="{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=blender platform=linux value=/linux/path/to/blender D:\Users\User\Desktop\flamenco-manager-2.5.1>time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=blender platform=linux value=/farm/path/to/blender time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=ffmpeg variable="{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="constructed lookup table" lookup="map[users:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/linux/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]] workers:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/farm/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]]]" variables="map[blender:{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]} ffmpeg:{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]} job_storage:{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]} render:{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}]" time="2019-09-17T09:38:49-05:00" level=debug msg="interpreting settings as version 2" time="2019-09-17T09:38:49-05:00" level=debug msg="constructing variable lookup table" concreteAudiences="[workers users]" isWildcard="map[:true all:true]" time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=blender variable="{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=blender platform=linux value=/linux/path/to/blender time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=blender platform=linux value=/farm/path/to/blender time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=ffmpeg variable="{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=render variable="{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=render platform=linux value=/render time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=render platform=linux value=/render time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=render platform=windows value="R:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=render platform=windows value="R:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=render platform=darwin value=/Volumes/render time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=render platform=darwin value=/Volumes/render time="2019-09-17T09:38:49-05:00" level=debug msg="handling variable" name=job_storage variable="{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]}" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=windows value="S:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=windows value="S:" time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-17T09:38:49-05:00" level=debug msg="constructed lookup table" lookup="map[users:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/linux/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]] workers:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/farm/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]]]" variables="map[blender:{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]} ffmpeg:{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]} job_storage:{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]} render:{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}]" time="2019-09-17T09:38:49-05:00" level=info msg="Run mode" mode=production time="2019-09-17T09:38:49-05:00" level=info msg="Flamenco Server URL" url="https://cloud.blender.org/" time="2019-09-17T09:38:49-05:00" level=info msg="Entering setup mode" securityEnabled=true time="2019-09-17T09:38:50-05:00" level=debug msg="Credentials are still valid, no need to link." time="2019-09-17T09:38:50-05:00" level=debug msg="unable to read private key" error="open D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem: The system cannot find the file specified." keyPath="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem" package=jwtauth time="2019-09-17T09:38:50-05:00" level=debug msg="no public keys found" keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth time="2019-09-17T09:38:50-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-17T09:38:50-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-17T09:38:50-05:00" level=debug msg="downloading JWT public keys" keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth url="https://cloud.blender.org/api/flamenco/jwt/public-keys" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-17T09:38:50-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-17T09:38:50-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-17T09:38:50-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-17T09:38:50-05:00" level=info msg="Point your browser at any of these URLs:" time="2019-09-17T09:38:50-05:00" level=info msg=" - http://192.168.2.30:8080/setup" time="2019-09-17T09:38:50-05:00" level=debug msg="Found templates in current working directory" file_to_find=templates/layout.html time="2019-09-17T09:38:50-05:00" level=info msg="creating insecure server" listen=":8080" time="2019-09-17T09:38:50-05:00" level=debug msg="starting HTTP server" listen=":8080" time="2019-09-17T09:38:50-05:00" level=info msg="JWT public keys downloaded" filename="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/downloaded-public.pem" http_status=200 keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth url="https://cloud.blender.org/api/flamenco/jwt/public-keys" time="2019-09-17T09:38:50-05:00" level=debug msg="downloaded new keys to disk, going to load them to memory" package=jwtauth time="2019-09-17T09:38:50-05:00" level=debug msg="unable to read private key" error="open D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem: The system cannot find the file specified." keyPath="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem" package=jwtauth time="2019-09-17T09:38:50-05:00" level=info msg="loaded JWT public keys" keyCount=1 keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth time="2019-09-17T09:38:50-05:00" level=info msg="starting JWT public key download loop" interval=1h0m0s package=jwtauth time="2019-09-17T09:38:52-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-17T09:38:52-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-17T09:38:52-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-17T09:38:52-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-17T09:38:52-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-17T09:38:52-05:00" level=debug msg="no JWT token cookie" error="http: named cookie not present" remoteAddr="192.168.2.30:62230" requestMethod=GET requestURI=/setup/data time="2019-09-17T09:38:52-05:00" level=debug msg="no authorization header" remoteAddr="192.168.2.30:62230" requestMethod=GET requestURI=/setup/data time="2019-09-17T09:38:52-05:00" level=debug msg="redirecting user to obtain JWT token" package=jwtauth remoteAddr="192.168.2.30:62230" requestMethod=GET requestURI=/jwt/token-urls time="2019-09-17T09:38:52-05:00" level=debug msg="no JWT token cookie" error="http: named cookie not present" remoteAddr="192.168.2.30:62230" requestMethod=GET requestURI=/setup/api/link-required time="2019-09-17T09:38:52-05:00" level=debug msg="no authorization header" remoteAddr="192.168.2.30:62230" requestMethod=GET requestURI=/setup/api/link-required

sybrenstuvel commented 5 years ago

time="2019-09-17T09:38:52-05:00" level=debug msg="no JWT token cookie" error="http: named cookie not present" remoteAddr="192.168.2.30:62230" requestMethod=GET requestURI=/setup/api/link-required

It looks like your browser isn't sending the correct cookie. Which browser are you using?

ArrowThief commented 5 years ago

This log is from Google chrome, though I also tested it with edge and got the same result so I figured it wasn't a browser issue. I'll try it with Firefox and post the results.

Edit: Here is the log, same issue. I also attempted it with brave and had similar results though it started reloading the setup page over and over after attempting to link a manager.

menco-manager.yaml" time="2019-09-24T09:13:32-05:00" level=warning msg="Restarting Flamenco Manager into setup mode by request of the web setup." remote_addr="192.168.2.30:53762" time="2019-09-24T09:13:32-05:00" level=info msg="Signal received, shutting down." signal=interrupt time="2019-09-24T09:13:32-05:00" level=info msg="Shutting down HTTP server" time="2019-09-24T09:13:32-05:00" level=info msg="Waiting for shutdown to complete." time="2019-09-24T09:13:32-05:00" level=warning msg="Shutdown complete, stopping process." time="2019-09-24T09:13:32-05:00" level=warning msg="Restarting Flamenco Server" time="2019-09-24T09:13:32-05:00" level=info msg="Started another Flamenco Manager" args="[-mode -debug -setup -kill-after-start 20948]" exename="D:\Users\User\Desktop\flamenco-manager-2.5.1\flamenco-manager.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="waiting for our child process to kill us" time="2019-09-24T09:13:32-05:00" level=info msg="Starting Flamenco Manager" version=v2.5.1 time="2019-09-24T09:13:32-05:00" level=debug msg="Parent process terminated." pid=20948 time="2019-09-24T09:13:32-05:00" level=debug msg="constructing variable lookup table" concreteAudiences="[workers users]" isWildcard="map[:true all:true]" time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=render variable="{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=render platform=linux value=/render time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=render platform=linux value=/render time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=render platform=windows value="R:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=render platform=windows value="R:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=render platform=darwin value=/Volumes/render time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=render platform=darwin value=/Volumes/render time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=job_storage variable="{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=windows value="S:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=windows value="S:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs

time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs D:\Users\User\Desktop\flamenco-manager-2.5.1>time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=blender variable="{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=blender platform=linux value=/linux/path/to/blender time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=blender platform=linux value=/farm/path/to/blender time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=ffmpeg variable="{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="constructed lookup table" lookup="map[users:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/linux/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]] workers:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/farm/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]]]" variables="map[blender:{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]} ffmpeg:{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]} job_storage:{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]} render:{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}]" time="2019-09-24T09:13:32-05:00" level=debug msg="interpreting settings as version 2" time="2019-09-24T09:13:32-05:00" level=debug msg="constructing variable lookup table" concreteAudiences="[workers users]" isWildcard="map[:true all:true]" time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=job_storage variable="{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=linux value=/shared/flamenco-jobs time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=windows value="S:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=windows value="S:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=job_storage platform=darwin value=/Volumes/shared/flamenco-jobs time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=blender variable="{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=blender platform=linux value=/linux/path/to/blender time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=blender platform=linux value=/farm/path/to/blender time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=blender platform=windows value="C:/windows/path/to/blender.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=blender platform=darwin value=/Volumes/Applications/Blender/blender time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=ffmpeg variable="{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=linux value=/usr/bin/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=windows value="C:/windows/path/to/ffmpeg.exe" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=ffmpeg platform=darwin value=/Volumes/Applications/FFmpeg/ffmpeg time="2019-09-24T09:13:32-05:00" level=debug msg="handling variable" name=render variable="{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=render platform=linux value=/render time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=render platform=linux value=/render time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=render platform=windows value="R:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=render platform=windows value="R:" time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=workers name=render platform=darwin value=/Volumes/render time="2019-09-24T09:13:32-05:00" level=debug msg="setting variable" audience=users name=render platform=darwin value=/Volumes/render time="2019-09-24T09:13:32-05:00" level=debug msg="constructed lookup table" lookup="map[users:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/linux/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]] workers:map[darwin:map[blender:/Volumes/Applications/Blender/blender ffmpeg:/Volumes/Applications/FFmpeg/ffmpeg job_storage:/Volumes/shared/flamenco-jobs render:/Volumes/render] linux:map[blender:/farm/path/to/blender ffmpeg:/usr/bin/ffmpeg job_storage:/shared/flamenco-jobs render:/render] windows:map[blender:C:/windows/path/to/blender.exe ffmpeg:C:/windows/path/to/ffmpeg.exe job_storage:S: render:R:]]]" variables="map[blender:{oneway [{users linux [] /linux/path/to/blender} {workers linux [] /farm/path/to/blender} { windows [] C:/windows/path/to/blender.exe} { darwin [] /Volumes/Applications/Blender/blender}]} ffmpeg:{oneway [{ linux [] /usr/bin/ffmpeg} { windows [] C:/windows/path/to/ffmpeg.exe} { darwin [] /Volumes/Applications/FFmpeg/ffmpeg}]} job_storage:{twoway [{ linux [] /shared/flamenco-jobs} { windows [] S:} { darwin [] /Volumes/shared/flamenco-jobs}]} render:{twoway [{ linux [] /render} { windows [] R:} { darwin [] /Volumes/render}]}]" time="2019-09-24T09:13:32-05:00" level=info msg="Run mode" mode=production time="2019-09-24T09:13:32-05:00" level=info msg="Flamenco Server URL" url="https://cloud.blender.org/" time="2019-09-24T09:13:32-05:00" level=info msg="Entering setup mode" securityEnabled=true time="2019-09-24T09:13:33-05:00" level=debug msg="Credentials are still valid, no need to link." time="2019-09-24T09:13:33-05:00" level=debug msg="unable to read private key" error="open D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem: The system cannot find the file specified." keyPath="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem" package=jwtauth time="2019-09-24T09:13:33-05:00" level=debug msg="no public keys found" keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth time="2019-09-24T09:13:33-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-24T09:13:33-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-24T09:13:33-05:00" level=debug msg="downloading JWT public keys" keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth url="https://cloud.blender.org/api/flamenco/jwt/public-keys" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-24T09:13:33-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-24T09:13:33-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-24T09:13:33-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-24T09:13:33-05:00" level=info msg="Point your browser at any of these URLs:" time="2019-09-24T09:13:33-05:00" level=info msg=" - http://192.168.2.30:8080/setup" time="2019-09-24T09:13:33-05:00" level=debug msg="Found templates in current working directory" file_to_find=templates/layout.html time="2019-09-24T09:13:33-05:00" level=info msg="creating insecure server" listen=":8080" time="2019-09-24T09:13:33-05:00" level=debug msg="starting HTTP server" listen=":8080" time="2019-09-24T09:13:33-05:00" level=info msg="JWT public keys downloaded" filename="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/downloaded-public.pem" http_status=200 keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth url="https://cloud.blender.org/api/flamenco/jwt/public-keys" time="2019-09-24T09:13:33-05:00" level=debug msg="downloaded new keys to disk, going to load them to memory" package=jwtauth time="2019-09-24T09:13:33-05:00" level=debug msg="unable to read private key" error="open D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem: The system cannot find the file specified." keyPath="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem" package=jwtauth time="2019-09-24T09:13:33-05:00" level=info msg="loaded JWT public keys" keyCount=1 keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth time="2019-09-24T09:13:33-05:00" level=info msg="starting JWT public key download loop" interval=1h0m0s package=jwtauth time="2019-09-24T09:13:35-05:00" level=debug msg="Not listening on any specific host ''" time="2019-09-24T09:13:35-05:00" level=debug msg="Iterating over all network interfaces." time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local fe80::c5ac:10fa:34d8:7a01" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local 169.254.122.1" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local fe80::24be:db26:d7b0:ab51" time="2019-09-24T09:13:35-05:00" level=debug msg=" - usable 192.168.2.30" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local fe80::1dcb:7f8b:5bda:cd72" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local 169.254.205.114" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local fe80::79a2:fa3a:827c:fea7" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local 169.254.254.167" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local fe80::65d9:cc6:f736:d869" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping link-local 169.254.216.105" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping localhost ::1" time="2019-09-24T09:13:35-05:00" level=debug msg=" - skipping localhost 127.0.0.1" time="2019-09-24T09:13:35-05:00" level=debug msg="Iterating network interfaces to find possible URLs for Flamenco Manager." time="2019-09-24T09:13:35-05:00" level=debug msg="no JWT token cookie" error="http: named cookie not present" remoteAddr="192.168.2.30:53780" requestMethod=GET requestURI=/setup/data time="2019-09-24T09:13:35-05:00" level=debug msg="no authorization header" remoteAddr="192.168.2.30:53780" requestMethod=GET requestURI=/setup/data time="2019-09-24T09:13:35-05:00" level=debug msg="redirecting user to obtain JWT token" package=jwtauth remoteAddr="192.168.2.30:53780" requestMethod=GET requestURI=/jwt/token-urls time="2019-09-24T09:13:35-05:00" level=debug msg="no JWT token cookie" error="http: named cookie not present" remoteAddr="192.168.2.30:53780" requestMethod=GET requestURI=/setup/api/link-required time="2019-09-24T09:13:35-05:00" level=debug msg="no authorization header" remoteAddr="192.168.2.30:53780" requestMethod=GET requestURI=/setup/api/link-required

sybrenstuvel commented 4 years ago

This is quite strange:

time="2019-09-24T09:13:33-05:00" level=info msg="JWT public keys downloaded" filename="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/downloaded-public.pem" http_status=200 keyDirectory="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys" package=jwtauth url="https://cloud.blender.org/api/flamenco/jwt/public-keys"
time="2019-09-24T09:13:33-05:00" level=debug msg="downloaded new keys to disk, going to load them to memory" package=jwtauth
time="2019-09-24T09:13:33-05:00" level=debug msg="unable to read private key" error="open D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem: The system cannot find the file specified." keyPath="D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem" package=jwtauth

Can you check whether D:\Users\User\Desktop\flamenco-manager-2.5.1/jwtkeys/es256-private.pem exists, and matches https://cloud.blender.org/api/flamenco/jwt/public-keys ?

ArrowThief commented 4 years ago

Sorry for taking so long to respond to this, the past few months have been... fun. To answer your question, both the URL and my downloaded-public.pem file are the same. I assume the name of the file has changed since 2.5.1 since I can't find es256-private.pem in the jwtkeys folder.

I solved this temporarily by using a Linux install but then had to bring down that system, so I switched back to windows thinking this wouldn't be an issue in 2.7. Sadly that is not the case. After further debugging I believe the issue isn't on the server but is actually on the client side. To reiterate, I start the server in setup mode, I login to cloud.blender.org to add it as a manger, restart the server to setup mode to continue the setup and I receive an error saying "Unable to obtain authorization token". Several of the setup options disappear at this point also, probably due to my client not having privileges to view or edit them. Looking at the servers log I see this when I connect, :

time="2020-07-17T10:45:19-05:00" level=debug msg="redirecting user to obtain JWT token" package=jwtauth remoteAddr="192.168.1.111:9952" requestMethod=GET requestURI=/jwt/token-urls time="2020-07-17T10:45:19-05:00" level=debug msg="no JWT token cookie" error="http: named cookie not present" remoteAddr="192.168.1.111:9952" requestMethod=GET requestURI=/setup/api/link-required time="2020-07-17T10:45:19-05:00" level=debug msg="no authorization header" remoteAddr="192.168.1.111:9952" requestMethod=GET requestURI=/setup/api/link-required

I thought it may be an issue with chrome, so I tried Iexplorer with no success and then Firefox, same issue. To try and be thorough, I also tried accessing the server from a Ubuntu client, still got the same issue. I also tried running the manager on a windows 10 machine and a windows server 2016 machine. Same issues on both.

One other very strange note: In doing this over and over to try and debug it, it worked normally once. I was able to get fully setup and everything worked correctly. That lasted for about two weeks until the same issue started happening when I tried to view the managers web interface. Stranger still, It still worked to manage the workers. It was rendering files normally I just couldn't access the web interface.

sybrenstuvel commented 2 years ago

Sorry for taking so long to respond to this, the past few months have been... fun. I know all about those months ;-)

Did you manage to solve the issue?

ArrowThief commented 2 years ago

Yes, after updating to 2.7 (and later 2.73) I am no longer running into this issue. Thanks for your help.