· 4 years ago · Jan 12, 2021, 12:38 PM
1PS C:\Projects\big-forest\docker-dev> docker-compose up
2Starting docker-dev_hub-rabbitmq_1 ... done
3Starting docker-dev_hub-db_1 ... done
4Starting docker-dev_classifier-db_1 ... done
5Starting docker-dev_classifier_1 ... done
6Recreating docker-dev_bf-xlsx-worker_1 ... done
7Starting docker-dev_hub_1 ... done
8Starting docker-dev_nginx_1 ... done
9Attaching to docker-dev_classifier-db_1, docker-dev_hub-db_1, docker-dev_hub-rabbitmq_1, docker-dev_classifier_1, docker-dev_hub_1, docker-dev_bf-xlsx-worker_1, docker-dev_nginx_1
10classifier-db_1 | 2021-01-12 12:09:23.881 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432
11classifier-db_1 | 2021-01-12 12:09:23.881 UTC [1] LOG: listening on IPv6 address "::", port 5432
12classifier-db_1 | 2021-01-12 12:09:23.908 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
13classifier-db_1 | 2021-01-12 12:09:24.027 UTC [22] LOG: database system was interrupted; last known up at 2021-01-12 11:59:37 UTC
14classifier-db_1 | 2021-01-12 12:09:25.544 UTC [22] LOG: database system was not properly shut down; automatic recovery in progress
15classifier-db_1 | 2021-01-12 12:09:25.554 UTC [22] LOG: redo starts at 0/3069AA8
16classifier-db_1 | 2021-01-12 12:09:25.554 UTC [22] LOG: invalid record length at 0/3069B88: wanted 24, got 0
17classifier-db_1 | 2021-01-12 12:09:25.554 UTC [22] LOG: redo done at 0/3069B50
18classifier-db_1 | 2021-01-12 12:09:25.674 UTC [1] LOG: database system is ready to accept connections
19classifier_1 | 2021-01-12 12:09:26.246 | INFO | __main__:main:24 - hub config:
20classifier_1 | {
21classifier_1 | "configuration_file": null,
22classifier_1 | "log_level": "debug",
23classifier_1 | "log_config": null,
24classifier_1 | "web_host": "0.0.0.0",
25classifier_1 | "web_port": 80,
26classifier_1 | "web_healthcheck_name": "noname",
27classifier_1 | "web_access_log_format": "%a -> \"%r\" -> %s [%Tf sec]",
28classifier_1 | "web_hide_api_description_route": false,
29classifier_1 | "executors_io_threads_count": 32,
30classifier_1 | "executors_cpu_threads_count": 6,
31classifier_1 | "db_host": "classifier-db",
32classifier_1 | "db_port": 5432,
33classifier_1 | "db_user": "postgres",
34classifier_1 | "db_password": "admin",
35classifier_1 | "db_database": "classifier",
36classifier_1 | "db_connection_attempts": 60,
37classifier_1 | "db_connection_interval": 1.0,
38classifier_1 | "db_pool_min_size": 2,
39classifier_1 | "db_pool_max_size": 10,
40classifier_1 | "db_migration_script_location": null,
41classifier_1 | "db_migration_target_revision": null,
42classifier_1 | "i18n_load_path_module": null,
43classifier_1 | "i18n_supported_locales": [
44classifier_1 | "en",
45classifier_1 | "ru"
46classifier_1 | ],
47classifier_1 | "i18n_default_locale": "ru",
48classifier_1 | "i18n_generate_stubs_for": [
49classifier_1 | "ru"
50classifier_1 | ],
51classifier_1 | "access_secret_key": "4e4b4df9-d37d-44ac-8ebf-e9a1b703590f",
52classifier_1 | "access_max_sessions": 3,
53classifier_1 | "access_max_login_attempts": 3,
54classifier_1 | "access_session_lifetime": 86400,
55classifier_1 | "access_login_block_timeout": 60
56classifier_1 | }
57classifier_1 | 2021-01-12 12:09:26.249 | DEBUG | asyncio.selector_events:__init__:59 - Using selector: EpollSelector
58classifier_1 | 2021-01-12 12:09:26.314 | DEBUG | newcore.postgresql:start:48 - Successfully connected to database
59classifier_1 | 2021-01-12 12:09:26.345 | INFO | desk.api.high:migrate:73 - Database is initially at 001, target revision is <head>
60classifier_1 | 2021-01-12 12:09:26.345 | INFO | desk.api.high:migrate:86 - No migrations applied
61hub-db_1 | 2021-01-12 12:09:24.255 UTC [1] LOG: listening on IPv4 address "0.0.0.0", port 5432
62hub-db_1 | 2021-01-12 12:09:24.255 UTC [1] LOG: listening on IPv6 address "::", port 5432
63hub-db_1 | 2021-01-12 12:09:24.292 UTC [1] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432"
64hub-db_1 | 2021-01-12 12:09:24.357 UTC [22] LOG: database system was shut down at 2021-01-12 12:09:14 UTC
65hub-db_1 | 2021-01-12 12:09:24.408 UTC [1] LOG: database system is ready to accept connections
66nginx_1 | /docker-entrypoint.sh: /docker-entrypoint.d/ is not empty, will attempt to perform configuration
67nginx_1 | /docker-entrypoint.sh: Looking for shell scripts in /docker-entrypoint.d/
68nginx_1 | /docker-entrypoint.sh: Launching /docker-entrypoint.d/10-listen-on-ipv6-by-default.sh
69nginx_1 | 10-listen-on-ipv6-by-default.sh: info: Getting the checksum of /etc/nginx/conf.d/default.conf
70nginx_1 | 10-listen-on-ipv6-by-default.sh: info: /etc/nginx/conf.d/default.conf differs from the packaged version
71nginx_1 | /docker-entrypoint.sh: Launching /docker-entrypoint.d/20-envsubst-on-templates.sh
72nginx_1 | /docker-entrypoint.sh: Configuration complete; ready for start up
73hub_1 | 2021-01-12 12:09:26.789 | INFO | __main__:main:33 - hub config:
74hub_1 | {
75hub_1 | "configuration_file": null,
76hub_1 | "log_level": "debug",
77hub_1 | "log_config": null,
78hub_1 | "web_host": "0.0.0.0",
79hub_1 | "web_port": 80,
80hub_1 | "web_healthcheck_name": "noname",
81hub_1 | "web_access_log_format": "%a -> \"%r\" -> %s [%Tf sec]",
82hub_1 | "web_hide_api_description_route": false,
83hub_1 | "executors_io_threads_count": 32,
84hub_1 | "executors_cpu_threads_count": 6,
85hub_1 | "db_host": "hub-db",
86hub_1 | "db_port": 5432,
87hub_1 | "db_user": "postgres",
88hub_1 | "db_password": "admin",
89hub_1 | "db_database": "hub",
90hub_1 | "db_connection_attempts": 60,
91hub_1 | "db_connection_interval": 1.0,
92hub_1 | "db_pool_min_size": 2,
93hub_1 | "db_pool_max_size": 10,
94hub_1 | "db_migration_script_location": "hub.db_migrations",
95hub_1 | "db_migration_target_revision": null,
96hub_1 | "i18n_load_path_module": "storage.i18n",
97hub_1 | "i18n_supported_locales": [
98hub_1 | "en",
99hub_1 | "ru"
100hub_1 | ],
101hub_1 | "i18n_default_locale": "ru",
102hub_1 | "i18n_generate_stubs_for": [
103hub_1 | "ru"
104hub_1 | ],
105hub_1 | "access_secret_key": "3b765f0c-7524-4762-afae-4e5514c1dfee",
106hub_1 | "access_max_sessions": 3,
107hub_1 | "access_max_login_attempts": 3,
108hub_1 | "access_session_lifetime": 86400,
109hub_1 | "access_login_block_timeout": 60,
110hub_1 | "storage_root": "/storage/main",
111hub_1 | "storage_base_url": "/storage/",
112hub_1 | "storage_max_unzip_memory": 104857600,
113hub_1 | "storage_executables_unzip": "/usr/bin/unzip",
114hub_1 | "files_keep_alive_days": 5.0,
115hub_1 | "files_check_interval_hours": 0.5,
116hub_1 | "storage_roots": [
117hub_1 | "main:/storage/main"
118hub_1 | ],
119hub_1 | "rmq_host": "hub-rabbitmq",
120hub_1 | "rmq_port": 5672,
121hub_1 | "rmq_user": "guest",
122hub_1 | "rmq_password": "guest",
123hub_1 | "rmq_virtual_host": "",
124hub_1 | "rmq_connection_attempts": 60,
125hub_1 | "rmq_connection_interval": 1.0,
126hub_1 | "uploader_base_url": "http://localhost/uploads"
127hub_1 | }
128hub_1 | 2021-01-12 12:09:26.790 | DEBUG | asyncio.selector_events:__init__:59 - Using selector: EpollSelector
129hub_1 | 2021-01-12 12:09:26.823 | DEBUG | newcore.postgresql:start:48 - Successfully connected to database
130hub_1 | 2021-01-12 12:09:26.831 | INFO | newcore.postgresql:migrate:127 - Database is initially at 001, target revision is <head>
131hub_1 | 2021-01-12 12:09:26.831 | INFO | newcore.postgresql:migrate:140 - No migrations applied
132hub_1 | 2021-01-12 12:09:26.847 | INFO | uploader.storage_factory:storage_factory_from_config:36 - Registered storage roots:
133hub_1 | main: /storage/main
134hub_1 | 2021-01-12 12:09:26.887 | DEBUG | newcore.postgresql:start:48 - Successfully connected to database
135bf-xlsx-worker_1 | 2021-01-12 12:09:27.083 | INFO | __main__:main:26 - xlsx-worker config:
136bf-xlsx-worker_1 | {
137bf-xlsx-worker_1 | "configuration_file": null,
138bf-xlsx-worker_1 | "log_level": "debug",
139bf-xlsx-worker_1 | "log_config": null,
140bf-xlsx-worker_1 | "web_host": "0.0.0.0",
141bf-xlsx-worker_1 | "web_port": 80,
142bf-xlsx-worker_1 | "web_healthcheck_name": "xlsx-worker",
143bf-xlsx-worker_1 | "web_access_log_format": "%a -> \"%r\" -> %s [%Tf sec]",
144bf-xlsx-worker_1 | "web_hide_api_description_route": false,
145bf-xlsx-worker_1 | "executors_io_threads_count": 32,
146bf-xlsx-worker_1 | "executors_cpu_threads_count": 6,
147bf-xlsx-worker_1 | "db_host": "hub-db",
148bf-xlsx-worker_1 | "db_port": 5432,
149bf-xlsx-worker_1 | "db_user": "postgres",
150bf-xlsx-worker_1 | "db_password": "admin",
151bf-xlsx-worker_1 | "db_database": "hub",
152bf-xlsx-worker_1 | "db_connection_attempts": 60,
153bf-xlsx-worker_1 | "db_connection_interval": 1.0,
154bf-xlsx-worker_1 | "db_pool_min_size": 2,
155bf-xlsx-worker_1 | "db_pool_max_size": 10,
156bf-xlsx-worker_1 | "db_migration_script_location": null,
157bf-xlsx-worker_1 | "db_migration_target_revision": null,
158bf-xlsx-worker_1 | "i18n_load_path_module": null,
159bf-xlsx-worker_1 | "i18n_supported_locales": [
160bf-xlsx-worker_1 | "en",
161bf-xlsx-worker_1 | "ru"
162bf-xlsx-worker_1 | ],
163bf-xlsx-worker_1 | "i18n_default_locale": "ru",
164bf-xlsx-worker_1 | "i18n_generate_stubs_for": [
165bf-xlsx-worker_1 | "ru"
166bf-xlsx-worker_1 | ],
167bf-xlsx-worker_1 | "access_secret_key": "ea7311f6-ed5b-4536-8bc5-11c6eac35d5b",
168bf-xlsx-worker_1 | "access_max_sessions": 3,
169bf-xlsx-worker_1 | "access_max_login_attempts": 3,
170bf-xlsx-worker_1 | "access_session_lifetime": 86400,
171bf-xlsx-worker_1 | "access_login_block_timeout": 60,
172bf-xlsx-worker_1 | "rmq_host": "hub-rabbitmq",
173bf-xlsx-worker_1 | "rmq_port": 5672,
174bf-xlsx-worker_1 | "rmq_user": "guest",
175bf-xlsx-worker_1 | "rmq_password": "guest",
176bf-xlsx-worker_1 | "rmq_virtual_host": "",
177bf-xlsx-worker_1 | "rmq_connection_attempts": 60,
178bf-xlsx-worker_1 | "rmq_connection_interval": 1.0,
179bf-xlsx-worker_1 | "runner_type": "generic"
180bf-xlsx-worker_1 | }
181bf-xlsx-worker_1 | 2021-01-12 12:09:27.085 | DEBUG | asyncio.selector_events:__init__:59 - Using selector: EpollSelector
182bf-xlsx-worker_1 | 2021-01-12 12:09:27.113 | DEBUG | newcore.postgresql:start:48 - Successfully connected to database
183hub_1 | 2021-01-12 12:09:27.373 | DEBUG | aiohttp_sse_client.client:connect:184 - connect
184hub-rabbitmq_1 | Configuring logger redirection
185hub-rabbitmq_1 | 2021-01-12 12:09:28.962 [debug] <0.287.0> Lager installed handler error_logger_lager_h into error_logger
186hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.311.0> Lager installed handler lager_forwarder_backend into rabbit_log_mirroring_lager_event
187hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.326.0> Lager installed handler lager_forwarder_backend into rabbit_log_upgrade_lager_event
188hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.290.0> Lager installed handler lager_forwarder_backend into error_logger_lager_event
189hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.293.0> Lager installed handler lager_forwarder_backend into rabbit_log_lager_event
190hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.296.0> Lager installed handler lager_forwarder_backend into rabbit_log_channel_lager_event
191hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.299.0> Lager installed handler lager_forwarder_backend into rabbit_log_connection_lager_event
192hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.302.0> Lager installed handler lager_forwarder_backend into rabbit_log_feature_flags_lager_event
193hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.305.0> Lager installed handler lager_forwarder_backend into rabbit_log_federation_lager_event
194hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.308.0> Lager installed handler lager_forwarder_backend into rabbit_log_ldap_lager_event
195hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.314.0> Lager installed handler lager_forwarder_backend into rabbit_log_prelaunch_lager_event
196hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.317.0> Lager installed handler lager_forwarder_backend into rabbit_log_queue_lager_event
197hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.320.0> Lager installed handler lager_forwarder_backend into rabbit_log_ra_lager_event
198hub-rabbitmq_1 | 2021-01-12 12:09:28.980 [debug] <0.323.0> Lager installed handler lager_forwarder_backend into rabbit_log_shovel_lager_event
199hub-rabbitmq_1 | 2021-01-12 12:09:28.998 [info] <0.44.0> Application lager started on node rabbit@5f6e40ede307
200hub-rabbitmq_1 | 2021-01-12 12:09:29.463 [debug] <0.283.0> Lager installed handler lager_backend_throttle into lager_event
201hub-rabbitmq_1 | 2021-01-12 12:09:30.822 [info] <0.44.0> Application mnesia started on node rabbit@5f6e40ede307
202hub-rabbitmq_1 | 2021-01-12 12:09:30.823 [info] <0.272.0>
203hub-rabbitmq_1 | Starting RabbitMQ 3.8.9 on Erlang 23.2.1
204hub-rabbitmq_1 | Copyright (c) 2007-2020 VMware, Inc. or its affiliates.
205hub-rabbitmq_1 | Licensed under the MPL 2.0. Website: https://rabbitmq.com
206hub-rabbitmq_1 |
207hub-rabbitmq_1 | ## ## RabbitMQ 3.8.9
208hub-rabbitmq_1 | ## ##
209hub-rabbitmq_1 | ########## Copyright (c) 2007-2020 VMware, Inc. or its affiliates.
210hub-rabbitmq_1 | ###### ##
211hub-rabbitmq_1 | ########## Licensed under the MPL 2.0. Website: https://rabbitmq.com
212hub-rabbitmq_1 |
213hub-rabbitmq_1 | Doc guides: https://rabbitmq.com/documentation.html
214hub-rabbitmq_1 | Support: https://rabbitmq.com/contact.html
215hub-rabbitmq_1 | Tutorials: https://rabbitmq.com/getstarted.html
216hub-rabbitmq_1 | Monitoring: https://rabbitmq.com/monitoring.html
217hub-rabbitmq_1 |
218hub-rabbitmq_1 | Logs: <stdout>
219hub-rabbitmq_1 |
220hub-rabbitmq_1 | Config file(s): /etc/rabbitmq/rabbitmq.conf
221hub-rabbitmq_1 | /etc/rabbitmq/conf.d/management_agent.disable_metrics_collector.conf
222hub-rabbitmq_1 |
223hub-rabbitmq_1 | Starting broker...2021-01-12 12:09:30.826 [info] <0.272.0>
224hub-rabbitmq_1 | node : rabbit@5f6e40ede307
225hub-rabbitmq_1 | home dir : /var/lib/rabbitmq
226hub-rabbitmq_1 | config file(s) : /etc/rabbitmq/rabbitmq.conf
227hub-rabbitmq_1 | : /etc/rabbitmq/conf.d/management_agent.disable_metrics_collector.conf
228hub-rabbitmq_1 | cookie hash : ro2a24OAjzly7ViepE08Rg==
229hub-rabbitmq_1 | log(s) : <stdout>
230hub-rabbitmq_1 | database dir : /var/lib/rabbitmq/mnesia/rabbit@5f6e40ede307
231hub-rabbitmq_1 | 2021-01-12 12:09:30.838 [info] <0.272.0> Running boot step pre_boot defined by app rabbit
232hub-rabbitmq_1 | 2021-01-12 12:09:30.838 [info] <0.272.0> Running boot step rabbit_core_metrics defined by app rabbit
233hub-rabbitmq_1 | 2021-01-12 12:09:30.840 [info] <0.272.0> Running boot step rabbit_alarm defined by app rabbit
234hub-rabbitmq_1 | 2021-01-12 12:09:30.846 [info] <0.399.0> Memory high watermark set to 795 MiB (833729331 bytes) of 1987 MiB (2084323328 bytes) total
235hub-rabbitmq_1 | 2021-01-12 12:09:30.851 [info] <0.415.0> Enabling free disk space monitoring
236hub-rabbitmq_1 | 2021-01-12 12:09:30.851 [info] <0.415.0> Disk free limit set to 50MB
237hub-rabbitmq_1 | 2021-01-12 12:09:30.856 [info] <0.272.0> Running boot step code_server_cache defined by app rabbit
238hub-rabbitmq_1 | 2021-01-12 12:09:30.856 [info] <0.272.0> Running boot step file_handle_cache defined by app rabbit
239hub-rabbitmq_1 | 2021-01-12 12:09:30.856 [info] <0.418.0> Limiting to approx 1048479 file handles (943629 sockets)
240hub-rabbitmq_1 | 2021-01-12 12:09:30.856 [info] <0.419.0> FHC read buffering: OFF
241hub-rabbitmq_1 | 2021-01-12 12:09:30.856 [info] <0.419.0> FHC write buffering: ON
242hub-rabbitmq_1 | 2021-01-12 12:09:30.857 [info] <0.272.0> Running boot step worker_pool defined by app rabbit
243hub-rabbitmq_1 | 2021-01-12 12:09:30.857 [info] <0.370.0> Will use 2 processes for default worker pool
244hub-rabbitmq_1 | 2021-01-12 12:09:30.857 [info] <0.370.0> Starting worker pool 'worker_pool' with 2 processes in it
245hub-rabbitmq_1 | 2021-01-12 12:09:30.857 [info] <0.272.0> Running boot step database defined by app rabbit
246hub-rabbitmq_1 | 2021-01-12 12:09:30.860 [info] <0.272.0> Waiting for Mnesia tables for 30000 ms, 9 retries left
247hub-rabbitmq_1 | 2021-01-12 12:09:30.867 [info] <0.272.0> Successfully synced tables from a peer
248hub-rabbitmq_1 | 2021-01-12 12:09:30.867 [info] <0.272.0> Waiting for Mnesia tables for 30000 ms, 9 retries left
249hub-rabbitmq_1 | 2021-01-12 12:09:30.867 [info] <0.272.0> Successfully synced tables from a peer
250hub-rabbitmq_1 | 2021-01-12 12:09:30.903 [info] <0.272.0> Waiting for Mnesia tables for 30000 ms, 9 retries left
251hub-rabbitmq_1 | 2021-01-12 12:09:30.903 [info] <0.272.0> Successfully synced tables from a peer
252hub-rabbitmq_1 | 2021-01-12 12:09:30.903 [info] <0.272.0> Peer discovery backend rabbit_peer_discovery_classic_config does not support registration, skipping registration.
253hub-rabbitmq_1 | 2021-01-12 12:09:30.904 [info] <0.272.0> Running boot step database_sync defined by app rabbit
254hub-rabbitmq_1 | 2021-01-12 12:09:30.904 [info] <0.272.0> Running boot step feature_flags defined by app rabbit
255hub-rabbitmq_1 | 2021-01-12 12:09:30.905 [info] <0.272.0> Running boot step codec_correctness_check defined by app rabbit
256hub-rabbitmq_1 | 2021-01-12 12:09:30.905 [info] <0.272.0> Running boot step external_infrastructure defined by app rabbit
257hub-rabbitmq_1 | 2021-01-12 12:09:30.905 [info] <0.272.0> Running boot step rabbit_registry defined by app rabbit
258hub-rabbitmq_1 | 2021-01-12 12:09:30.906 [info] <0.272.0> Running boot step rabbit_auth_mechanism_cr_demo defined by app rabbit
259hub-rabbitmq_1 | 2021-01-12 12:09:30.906 [info] <0.272.0> Running boot step rabbit_queue_location_random defined by app rabbit
260hub-rabbitmq_1 | 2021-01-12 12:09:30.906 [info] <0.272.0> Running boot step rabbit_event defined by app rabbit
261hub-rabbitmq_1 | 2021-01-12 12:09:30.907 [info] <0.272.0> Running boot step rabbit_auth_mechanism_amqplain defined by app rabbit
262hub-rabbitmq_1 | 2021-01-12 12:09:30.907 [info] <0.272.0> Running boot step rabbit_auth_mechanism_plain defined by app rabbit
263hub-rabbitmq_1 | 2021-01-12 12:09:30.908 [info] <0.272.0> Running boot step rabbit_exchange_type_direct defined by app rabbit
264hub-rabbitmq_1 | 2021-01-12 12:09:30.908 [info] <0.272.0> Running boot step rabbit_exchange_type_fanout defined by app rabbit
265hub-rabbitmq_1 | 2021-01-12 12:09:30.908 [info] <0.272.0> Running boot step rabbit_exchange_type_headers defined by app rabbit
266hub-rabbitmq_1 | 2021-01-12 12:09:30.909 [info] <0.272.0> Running boot step rabbit_exchange_type_topic defined by app rabbit
267hub-rabbitmq_1 | 2021-01-12 12:09:30.910 [info] <0.272.0> Running boot step rabbit_mirror_queue_mode_all defined by app rabbit
268hub-rabbitmq_1 | 2021-01-12 12:09:30.910 [info] <0.272.0> Running boot step rabbit_mirror_queue_mode_exactly defined by app rabbit
269hub-rabbitmq_1 | 2021-01-12 12:09:30.911 [info] <0.272.0> Running boot step rabbit_mirror_queue_mode_nodes defined by app rabbit
270hub-rabbitmq_1 | 2021-01-12 12:09:30.911 [info] <0.272.0> Running boot step rabbit_priority_queue defined by app rabbit
271hub-rabbitmq_1 | 2021-01-12 12:09:30.911 [info] <0.272.0> Priority queues enabled, real BQ is rabbit_variable_queue
272hub-rabbitmq_1 | 2021-01-12 12:09:30.911 [info] <0.272.0> Running boot step rabbit_queue_location_client_local defined by app rabbit
273hub-rabbitmq_1 | 2021-01-12 12:09:30.911 [info] <0.272.0> Running boot step rabbit_queue_location_min_masters defined by app rabbit
274hub-rabbitmq_1 | 2021-01-12 12:09:30.912 [info] <0.272.0> Running boot step kernel_ready defined by app rabbit
275hub-rabbitmq_1 | 2021-01-12 12:09:30.912 [info] <0.272.0> Running boot step rabbit_sysmon_minder defined by app rabbit
276hub-rabbitmq_1 | 2021-01-12 12:09:30.913 [info] <0.272.0> Running boot step rabbit_epmd_monitor defined by app rabbit
277hub-rabbitmq_1 | 2021-01-12 12:09:30.915 [info] <0.439.0> epmd monitor knows us, inter-node communication (distribution) port: 25672
278hub-rabbitmq_1 | 2021-01-12 12:09:30.915 [info] <0.272.0> Running boot step guid_generator defined by app rabbit
279hub-rabbitmq_1 | 2021-01-12 12:09:30.925 [info] <0.272.0> Running boot step rabbit_node_monitor defined by app rabbit
280hub-rabbitmq_1 | 2021-01-12 12:09:30.925 [info] <0.443.0> Starting rabbit_node_monitor
281hub-rabbitmq_1 | 2021-01-12 12:09:30.925 [info] <0.272.0> Running boot step delegate_sup defined by app rabbit
282hub-rabbitmq_1 | 2021-01-12 12:09:30.927 [info] <0.272.0> Running boot step rabbit_memory_monitor defined by app rabbit
283hub-rabbitmq_1 | 2021-01-12 12:09:30.928 [info] <0.272.0> Running boot step core_initialized defined by app rabbit
284hub-rabbitmq_1 | 2021-01-12 12:09:30.928 [info] <0.272.0> Running boot step upgrade_queues defined by app rabbit
285hub-rabbitmq_1 | 2021-01-12 12:09:30.944 [info] <0.272.0> Running boot step rabbit_connection_tracking defined by app rabbit
286hub-rabbitmq_1 | 2021-01-12 12:09:30.944 [info] <0.272.0> Running boot step rabbit_connection_tracking_handler defined by app rabbit
287hub-rabbitmq_1 | 2021-01-12 12:09:30.944 [info] <0.272.0> Running boot step rabbit_exchange_parameters defined by app rabbit
288hub-rabbitmq_1 | 2021-01-12 12:09:30.944 [info] <0.272.0> Running boot step rabbit_mirror_queue_misc defined by app rabbit
289hub-rabbitmq_1 | 2021-01-12 12:09:30.945 [info] <0.272.0> Running boot step rabbit_policies defined by app rabbit
290hub-rabbitmq_1 | 2021-01-12 12:09:30.947 [info] <0.272.0> Running boot step rabbit_policy defined by app rabbit
291hub-rabbitmq_1 | 2021-01-12 12:09:30.947 [info] <0.272.0> Running boot step rabbit_queue_location_validator defined by app rabbit
292hub-rabbitmq_1 | 2021-01-12 12:09:30.947 [info] <0.272.0> Running boot step rabbit_quorum_memory_manager defined by app rabbit
293hub-rabbitmq_1 | 2021-01-12 12:09:30.947 [info] <0.272.0> Running boot step rabbit_vhost_limit defined by app rabbit
294hub-rabbitmq_1 | 2021-01-12 12:09:30.948 [info] <0.272.0> Running boot step recovery defined by app rabbit
295hub-rabbitmq_1 | 2021-01-12 12:09:30.950 [info] <0.474.0> Making sure data directory '/var/lib/rabbitmq/mnesia/rabbit@5f6e40ede307/msg_stores/vhosts/628WB79CIFDYO9LJI6DKMI09L' for vhost '/' exists
296hub-rabbitmq_1 | 2021-01-12 12:09:30.961 [info] <0.474.0> Starting message stores for vhost '/'
297hub-rabbitmq_1 | 2021-01-12 12:09:30.961 [info] <0.478.0> Message store "628WB79CIFDYO9LJI6DKMI09L/msg_store_transient": using rabbit_msg_store_ets_index to provide index
298hub-rabbitmq_1 | 2021-01-12 12:09:30.964 [info] <0.474.0> Started message store of type transient for vhost '/'
299hub-rabbitmq_1 | 2021-01-12 12:09:30.965 [info] <0.482.0> Message store "628WB79CIFDYO9LJI6DKMI09L/msg_store_persistent": using rabbit_msg_store_ets_index to provide index
300hub-rabbitmq_1 | 2021-01-12 12:09:30.966 [warning] <0.482.0> Message store "628WB79CIFDYO9LJI6DKMI09L/msg_store_persistent": rebuilding indices from scratch
301hub-rabbitmq_1 | 2021-01-12 12:09:30.969 [info] <0.474.0> Started message store of type persistent for vhost '/'
302hub-rabbitmq_1 | 2021-01-12 12:09:30.984 [info] <0.272.0> Running boot step empty_db_check defined by app rabbit
303hub-rabbitmq_1 | 2021-01-12 12:09:30.984 [info] <0.272.0> Will not seed default virtual host and user: have definitions to load...
304hub-rabbitmq_1 | 2021-01-12 12:09:30.984 [info] <0.272.0> Running boot step rabbit_looking_glass defined by app rabbit
305hub-rabbitmq_1 | 2021-01-12 12:09:30.985 [info] <0.272.0> Running boot step rabbit_core_metrics_gc defined by app rabbit
306hub-rabbitmq_1 | 2021-01-12 12:09:30.985 [info] <0.272.0> Running boot step background_gc defined by app rabbit
307hub-rabbitmq_1 | 2021-01-12 12:09:30.986 [info] <0.272.0> Running boot step connection_tracking defined by app rabbit
308hub-rabbitmq_1 | 2021-01-12 12:09:30.986 [info] <0.272.0> Setting up a table for connection tracking on this node: tracked_connection_on_node_rabbit@5f6e40ede307
309hub-rabbitmq_1 | 2021-01-12 12:09:30.987 [info] <0.272.0> Setting up a table for per-vhost connection counting on this node: tracked_connection_per_vhost_on_node_rabbit@5f6e40ede307
310hub-rabbitmq_1 | 2021-01-12 12:09:30.987 [info] <0.272.0> Running boot step routing_ready defined by app rabbit
311hub-rabbitmq_1 | 2021-01-12 12:09:30.988 [info] <0.272.0> Running boot step pre_flight defined by app rabbit
312hub-rabbitmq_1 | 2021-01-12 12:09:30.988 [info] <0.272.0> Running boot step notify_cluster defined by app rabbit
313hub-rabbitmq_1 | 2021-01-12 12:09:30.988 [info] <0.272.0> Running boot step networking defined by app rabbit
314hub-rabbitmq_1 | 2021-01-12 12:09:30.988 [info] <0.272.0> Running boot step definition_import_worker_pool defined by app rabbit
315hub-rabbitmq_1 | 2021-01-12 12:09:30.989 [info] <0.370.0> Starting worker pool 'definition_import_pool' with 2 processes in it
316hub-rabbitmq_1 | 2021-01-12 12:09:30.991 [info] <0.272.0> Running boot step cluster_name defined by app rabbit
317hub-rabbitmq_1 | 2021-01-12 12:09:30.991 [info] <0.272.0> Running boot step direct_client defined by app rabbit
318hub-rabbitmq_1 | 2021-01-12 12:09:30.991 [info] <0.44.0> Application rabbit started on node rabbit@5f6e40ede307
319hub-rabbitmq_1 | 2021-01-12 12:09:31.370 [info] <0.522.0> Feature flags: list of feature flags found:
320hub-rabbitmq_1 | 2021-01-12 12:09:31.370 [info] <0.522.0> Feature flags: [ ] drop_unroutable_metric
321hub-rabbitmq_1 | 2021-01-12 12:09:31.370 [info] <0.522.0> Feature flags: [ ] empty_basic_get_metric
322hub-rabbitmq_1 | 2021-01-12 12:09:31.370 [info] <0.522.0> Feature flags: [x] implicit_default_bindings
323hub-rabbitmq_1 | 2021-01-12 12:09:31.370 [info] <0.522.0> Feature flags: [x] maintenance_mode_status
324hub-rabbitmq_1 | 2021-01-12 12:09:31.371 [info] <0.522.0> Feature flags: [x] quorum_queue
325hub-rabbitmq_1 | 2021-01-12 12:09:31.371 [info] <0.522.0> Feature flags: [x] virtual_host_metadata
326hub-rabbitmq_1 | 2021-01-12 12:09:31.371 [info] <0.522.0> Feature flags: feature flag states written to disk: yes
327hub-rabbitmq_1 | 2021-01-12 12:09:31.539 [info] <0.44.0> Application cowlib started on node rabbit@5f6e40ede307
328hub-rabbitmq_1 | 2021-01-12 12:09:31.550 [info] <0.44.0> Application cowboy started on node rabbit@5f6e40ede307
329hub-rabbitmq_1 | 2021-01-12 12:09:31.560 [info] <0.522.0> Running boot step rabbit_mgmt_db_handler defined by app rabbitmq_management_agent
330hub-rabbitmq_1 | 2021-01-12 12:09:31.561 [info] <0.522.0> Management plugin: using rates mode 'basic'
331hub-rabbitmq_1 | 2021-01-12 12:09:31.564 [info] <0.44.0> Application rabbitmq_management_agent started on node rabbit@5f6e40ede307
332hub-rabbitmq_1 | 2021-01-12 12:09:31.592 [info] <0.44.0> Application prometheus started on node rabbit@5f6e40ede307
333hub-rabbitmq_1 | 2021-01-12 12:09:31.604 [info] <0.44.0> Application rabbitmq_web_dispatch started on node rabbit@5f6e40ede307
334hub-rabbitmq_1 | 2021-01-12 12:09:31.617 [info] <0.553.0> Prometheus metrics: HTTP (non-TLS) listener started on port 15692
335hub-rabbitmq_1 | 2021-01-12 12:09:31.618 [info] <0.522.0> Ready to start client connection listeners
336hub-rabbitmq_1 | 2021-01-12 12:09:31.618 [info] <0.44.0> Application rabbitmq_prometheus started on node rabbit@5f6e40ede307
337hub-rabbitmq_1 | 2021-01-12 12:09:31.621 [info] <0.672.0> started TCP listener on [::]:5672
338hub-rabbitmq_1 | 2021-01-12 12:09:31.785 [info] <0.522.0> Server startup complete; 3 plugins started.
339hub-rabbitmq_1 | * rabbitmq_prometheus
340hub-rabbitmq_1 | * rabbitmq_web_dispatch
341hub-rabbitmq_1 | * rabbitmq_management_agent
342hub-rabbitmq_1 | completed with 3 plugins.
343hub-rabbitmq_1 | 2021-01-12 12:09:31.785 [info] <0.522.0> Resetting node maintenance status
344hub-rabbitmq_1 | 2021-01-12 12:09:31.879 [info] <0.675.0> accepting AMQP connection <0.675.0> (172.21.0.6:58892 -> 172.21.0.4:5672)
345hub-rabbitmq_1 | 2021-01-12 12:09:31.882 [info] <0.675.0> connection <0.675.0> (172.21.0.6:58892 -> 172.21.0.4:5672): user 'guest' authenticated and granted access to vhost '/'
346hub_1 | 2021-01-12 12:09:31.899 | DEBUG | aio_pika.connection:channel:183 - Creating AMQP channel for connection: <RobustConnection: "ampq://guest:******@hub-rabbitmq:5672" 0 channels>
347hub_1 | 2021-01-12 12:09:31.900 | DEBUG | aio_pika.connection:channel:192 - Channel created: <RobustChannel "None#Not initialized channel">
348hub_1 | 2021-01-12 12:09:31.903 | DEBUG | octopus.rabbitmq:start:58 - Successfully connected to broker
349hub_1 | 2021-01-12 12:09:31.904 | DEBUG | aio_pika.queue:declare:91 - Declaring queue: <Queue(progress_queue): auto_delete=False, durable=True, exclusive=False, arguments=None>
350hub_1 | 2021-01-12 12:09:31.907 | DEBUG | aio_pika.channel:declare_exchange:248 - Exchange declared <Exchange(progress_exchange): auto_delete=False, durable=True, arguments={})>
351hub_1 | 2021-01-12 12:09:31.907 | DEBUG | aio_pika.queue:bind:136 - Binding queue <Queue(progress_queue): auto_delete=False, durable=True, exclusive=False, arguments=None>: exchange=<Exchange(progress_exchange): auto_delete=False, durable=True, arguments={})>, routing_key='progress_queue', arguments=None
352hub_1 | 2021-01-12 12:09:31.910 | DEBUG | aio_pika.channel:declare_exchange:248 - Exchange declared <Exchange(notification_exchange): auto_delete=False, durable=True, arguments={})>
353hub_1 | 2021-01-12 12:09:31.911 | DEBUG | aio_pika.channel:declare_exchange:248 - Exchange declared <Exchange(task_exchange): auto_delete=False, durable=True, arguments={})>
354hub_1 | 2021-01-12 12:09:31.912 | DEBUG | aio_pika.queue:consume:226 - Start to consuming queue: <Queue(progress_queue): auto_delete=False, durable=True, exclusive=False, arguments=None>
355hub-rabbitmq_1 | 2021-01-12 12:09:32.097 [info] <0.687.0> accepting AMQP connection <0.687.0> (172.21.0.7:44734 -> 172.21.0.4:5672)
356hub-rabbitmq_1 | 2021-01-12 12:09:32.100 [info] <0.687.0> connection <0.687.0> (172.21.0.7:44734 -> 172.21.0.4:5672): user 'guest' authenticated and granted access to vhost '/'
357bf-xlsx-worker_1 | 2021-01-12 12:09:32.101 | DEBUG | aio_pika.connection:channel:183 - Creating AMQP channel for connection: <RobustConnection: "ampq://guest:******@hub-rabbitmq:5672" 0 channels>
358bf-xlsx-worker_1 | 2021-01-12 12:09:32.102 | DEBUG | aio_pika.connection:channel:192 - Channel created: <RobustChannel "None#Not initialized channel">
359bf-xlsx-worker_1 | 2021-01-12 12:09:32.105 | DEBUG | octopus.rabbitmq:start:58 - Successfully connected to broker
360bf-xlsx-worker_1 | 2021-01-12 12:09:32.106 | DEBUG | aio_pika.queue:declare:91 - Declaring queue: <Queue(task_queue_generic): auto_delete=False, durable=True, exclusive=False, arguments=None>
361bf-xlsx-worker_1 | 2021-01-12 12:09:32.108 | DEBUG | aio_pika.queue:declare:91 - Declaring queue: <Queue(amq_hmrfpeqvhxkviqsltzp72nmpv4): auto_delete=False, durable=None, exclusive=True, arguments=None>
362bf-xlsx-worker_1 | 2021-01-12 12:09:32.112 | DEBUG | aio_pika.channel:declare_exchange:248 - Exchange declared <Exchange(progress_exchange): auto_delete=False, durable=True, arguments={})>
363bf-xlsx-worker_1 | 2021-01-12 12:09:32.113 | DEBUG | aio_pika.channel:declare_exchange:248 - Exchange declared <Exchange(notification_exchange): auto_delete=False, durable=True, arguments={})>
364bf-xlsx-worker_1 | 2021-01-12 12:09:32.114 | DEBUG | aio_pika.channel:declare_exchange:248 - Exchange declared <Exchange(task_exchange): auto_delete=False, durable=True, arguments={})>
365bf-xlsx-worker_1 | 2021-01-12 12:09:32.115 | DEBUG | aio_pika.queue:bind:136 - Binding queue <Queue(task_queue_generic): auto_delete=False, durable=True, exclusive=False, arguments=None>: exchange=<Exchange(task_exchange): auto_delete=False, durable=True, arguments={})>, routing_key='generic', arguments=None
366bf-xlsx-worker_1 | 2021-01-12 12:09:32.117 | DEBUG | aio_pika.queue:bind:136 - Binding queue <Queue(amq_hmrfpeqvhxkviqsltzp72nmpv4): auto_delete=False, durable=None, exclusive=True, arguments=None>: exchange=<Exchange(notification_exchange): auto_delete=False, durable=True, arguments={})>, routing_key='amq_hmrfpeqvhxkviqsltzp72nmpv4', arguments=None
367bf-xlsx-worker_1 | 2021-01-12 12:09:32.119 | DEBUG | aio_pika.queue:consume:226 - Start to consuming queue: <Queue(task_queue_generic): auto_delete=False, durable=True, exclusive=False, arguments=None>
368bf-xlsx-worker_1 | 2021-01-12 12:09:32.119 | DEBUG | aio_pika.queue:consume:226 - Start to consuming queue: <Queue(amq_hmrfpeqvhxkviqsltzp72nmpv4): auto_delete=False, durable=None, exclusive=True, arguments=None>
369nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9 HTTP/1.1" 200 823 "http://localhost/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
370nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/chunk-vendors.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
371nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/app.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
372nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/0.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
373nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/AerialSurvey/DatasetView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
374nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/AerialSurvey/TablesView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
375nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/AerialSurvey/DatasetView~AerialSurvey/TablesView~FieldSurvey/TablesView~SatelliteImaging/TablesView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
376nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/ControlPanel.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
377nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/Catalogs.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
378nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/ErrorPageView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
379nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/Catalogs~ControlPanel.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
380nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/FieldSurvey/SamplePlotsOfForestSiteView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
381nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/FieldSurvey/TablesView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
382nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/ForestManagement.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
383nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/ForestManagement/ForestDistrictView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
384nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/ForestManagement/ImportTaskView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
385nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/ForestManagement/LocalForestryView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
386nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:30 +0000] "GET /js/SatelliteImaging/TablesView.js HTTP/1.1" 304 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
387hub_1 | 2021-01-12 12:12:31.503 | ERROR | newcore.web:error_middleware:189 - Unhandled exception:
388hub_1 | Traceback (most recent call last):
389hub_1 |
390hub_1 | File "/usr/local/lib/python3.8/site-packages/newcore/session_manager.py", line 75, in validate_session
391hub_1 | token = decode_jwt_token(self._secret_key, auth_token)
392hub_1 | │ │ │ └ 'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ1c2VyX2lkIjoiZmU3MjNjNmEtYjNjZS00OGQ3LTgwY2YtOTU1NWQzY2FmZjFkIiwic2Vzc2lvbl9pZCI6Ijc...
393hub_1 | │ │ └ '3b765f0c-7524-4762-afae-4e5514c1dfee'
394hub_1 | │ └ <hub.session_manager.SessionManager object at 0x7f96ffe9bf40>
395hub_1 | └ <function decode_jwt_token at 0x7f96ffeef670>
396hub_1 | File "/usr/local/lib/python3.8/site-packages/newcore/common.py", line 64, in decode_jwt_token
397hub_1 | return jwt.decode(token_encoded, secret_key, algorithms=(JWT_ALGORITHM,))
398hub_1 | │ │ │ │ └ 'HS256'
399hub_1 | │ │ │ └ '3b765f0c-7524-4762-afae-4e5514c1dfee'
400hub_1 | │ │ └ 'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ1c2VyX2lkIjoiZmU3MjNjNmEtYjNjZS00OGQ3LTgwY2YtOTU1NWQzY2FmZjFkIiwic2Vzc2lvbl9pZCI6Ijc...
401hub_1 | │ └ <bound method PyJWT.decode of <jwt.api_jwt.PyJWT object at 0x7f96ffedcaf0>>
402hub_1 | └ <module 'jwt' from '/usr/local/lib/python3.8/site-packages/jwt/__init__.py'>
403hub_1 | File "/usr/local/lib/python3.8/site-packages/jwt/api_jwt.py", line 91, in decode
404hub_1 | decoded = super(PyJWT, self).decode(
405hub_1 | │ └ <jwt.api_jwt.PyJWT object at 0x7f96ffedcaf0>
406hub_1 | └ <class 'jwt.api_jwt.PyJWT'>
407hub_1 | File "/usr/local/lib/python3.8/site-packages/jwt/api_jws.py", line 155, in decode
408hub_1 | self._verify_signature(payload, signing_input, header, signature,
409hub_1 | │ │ │ │ │ └ b'5\x8bu"u`\xf5,\xbdh\xd3\xd5\x9e\x15\xd9t\x1b\x8d\xc1\x00C\xb9\xbdN\xbf\x90\x96\xdf\x10\xd6i\x13'
410hub_1 | │ │ │ │ └ {'typ': 'JWT', 'alg': 'HS256'}
411hub_1 | │ │ │ └ b'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ1c2VyX2lkIjoiZmU3MjNjNmEtYjNjZS00OGQ3LTgwY2YtOTU1NWQzY2FmZjFkIiwic2Vzc2lvbl9pZCI6Ij...
412hub_1 | │ │ └ b'{"user_id":"fe723c6a-b3ce-48d7-80cf-9555d3caff1d","session_id":"75663a2bf1eb4c6fbed3ee32b2780bfa"}'hub_1 | │ └ <function PyJWS._verify_signature at 0x7f96ffe78ca0>
413hub_1 | └ <jwt.api_jwt.PyJWT object at 0x7f96ffedcaf0>
414hub_1 | File "/usr/local/lib/python3.8/site-packages/jwt/api_jws.py", line 223, in _verify_signature
415hub_1 | raise InvalidSignatureError('Signature verification failed')
416hub_1 | └ <class 'jwt.exceptions.InvalidSignatureError'>
417hub_1 |
418hub_1 | jwt.exceptions.InvalidSignatureError: Signature verification failed
419hub_1 |
420hub_1 |
421hub_1 | During handling of the above exception, another exception occurred:
422hub_1 |
423hub_1 |
424hub_1 | Traceback (most recent call last):
425hub_1 |
426hub_1 | File "/usr/local/lib/python3.8/runpy.py", line 194, in _run_module_as_main
427hub_1 | return _run_code(code, main_globals, None,
428hub_1 | │ │ └ {'__name__': '__main__', '__doc__': None, '__package__': 'hub', '__loader__': <_frozen_importlib_external.SourceFileLoader ob...
429hub_1 | │ └ <code object <module> at 0x7f9701cc0660, file "/bf-hub/hub/__main__.py", line 1>
430hub_1 | └ <function _run_code at 0x7f97021420d0>
431hub_1 | File "/usr/local/lib/python3.8/runpy.py", line 87, in _run_code
432hub_1 | exec(code, run_globals)
433hub_1 | │ └ {'__name__': '__main__', '__doc__': None, '__package__': 'hub', '__loader__': <_frozen_importlib_external.SourceFileLoader ob...
434hub_1 | └ <code object <module> at 0x7f9701cc0660, file "/bf-hub/hub/__main__.py", line 1>
435hub_1 |
436hub_1 | File "/bf-hub/hub/__main__.py", line 47, in <module>
437hub_1 | entrypoint(prog_name="hub")
438hub_1 | └ <Command entrypoint>
439hub_1 |
440hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 829, in __call__
441hub_1 | return self.main(*args, **kwargs)
442hub_1 | │ │ │ └ {'prog_name': 'hub'}
443hub_1 | │ │ └ ()
444hub_1 | │ └ <function BaseCommand.main at 0x7f97015f2430>
445hub_1 | └ <Command entrypoint>
446hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 782, in main
447hub_1 | rv = self.invoke(ctx)
448hub_1 | │ │ └ <click.core.Context object at 0x7f96ffef24c0>
449hub_1 | │ └ <function Command.invoke at 0x7f97015f2dc0>
450hub_1 | └ <Command entrypoint>
451hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 1066, in invoke
452hub_1 | return ctx.invoke(self.callback, **ctx.params)
453hub_1 | │ │ │ │ │ └ {'configuration_file': None, 'log_level': 'debug', 'log_config': None, 'web_host': '0.0.0.0', 'web_port': 80, 'web_healthchec...
454hub_1 | │ │ │ │ └ <click.core.Context object at 0x7f96ffef24c0>
455hub_1 | │ │ │ └ <function build_entrypoint.<locals>.entrypoint at 0x7f96ffe84b80>
456hub_1 | │ │ └ <Command entrypoint>
457hub_1 | │ └ <function Context.invoke at 0x7f97015f1ee0>
458hub_1 | └ <click.core.Context object at 0x7f96ffef24c0>
459hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 610, in invoke
460hub_1 | return callback(*args, **kwargs)
461hub_1 | │ │ └ {'configuration_file': None, 'log_level': 'debug', 'log_config': None, 'web_host': '0.0.0.0', 'web_port': 80, 'web_healthchec...
462hub_1 | │ └ ()
463hub_1 | └ <function build_entrypoint.<locals>.entrypoint at 0x7f96ffe84b80>
464hub_1 | File "/usr/local/lib/python3.8/site-packages/cock.py", line 81, in entrypoint
465hub_1 | return main(config)
466hub_1 | │ └ {'configuration_file': None, 'log_level': 'debug', 'log_config': None, 'web_host': '0.0.0.0', 'web_port': 80, 'web_healthchec...
467hub_1 | └ <function main at 0x7f970010f940>
468hub_1 |
469hub_1 | File "/bf-hub/hub/__main__.py", line 34, in main
470hub_1 | asyncio.run(amain())
471hub_1 | │ │ └ <function amain at 0x7f97020dc3a0>
472hub_1 | │ └ <function run at 0x7f9701ce2a60>
473hub_1 | └ <module 'asyncio' from '/usr/local/lib/python3.8/asyncio/__init__.py'>
474hub_1 |
475hub_1 | File "/usr/local/lib/python3.8/asyncio/runners.py", line 44, in run
476hub_1 | return loop.run_until_complete(main)
477hub_1 | │ │ └ <coroutine object amain at 0x7f96ffed95c0>
478hub_1 | │ └ <function BaseEventLoop.run_until_complete at 0x7f970170ea60>
479hub_1 | └ <_UnixSelectorEventLoop running=True closed=False debug=False>
480hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 603, in run_until_complete
481hub_1 | self.run_forever()
482hub_1 | │ └ <function BaseEventLoop.run_forever at 0x7f970170e9d0>
483hub_1 | └ <_UnixSelectorEventLoop running=True closed=False debug=False>
484hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 570, in run_forever
485hub_1 | self._run_once()
486hub_1 | │ └ <function BaseEventLoop._run_once at 0x7f9701691550>
487hub_1 | └ <_UnixSelectorEventLoop running=True closed=False debug=False>
488hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 1859, in _run_once
489hub_1 | handle._run()
490hub_1 | │ └ <function Handle._run at 0x7f970175a310>
491hub_1 | └ <Handle <TaskStepMethWrapper object at 0x7f96ff5dd7f0>()>
492hub_1 | File "/usr/local/lib/python3.8/asyncio/events.py", line 81, in _run
493hub_1 | self._context.run(self._callback, *self._args)
494hub_1 | │ │ │ │ │ └ <member '_args' of 'Handle' objects>
495hub_1 | │ │ │ │ └ <Handle <TaskStepMethWrapper object at 0x7f96ff5dd7f0>()>
496hub_1 | │ │ │ └ <member '_callback' of 'Handle' objects>
497hub_1 | │ │ └ <Handle <TaskStepMethWrapper object at 0x7f96ff5dd7f0>()>
498hub_1 | │ └ <member '_context' of 'Handle' objects>
499hub_1 | └ <Handle <TaskStepMethWrapper object at 0x7f96ff5dd7f0>()>
500hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 422, in _handle_request
501hub_1 | resp = await self._request_handler(request)
502hub_1 | │ │ └ <Request POST /users/get_current_user >
503hub_1 | │ └ <member '_request_handler' of 'RequestHandler' objects>
504hub_1 | └ <RequestHandler connected>
505hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/web_app.py", line 499, in _handle
506hub_1 | resp = await handler(request)
507hub_1 | │ └ <Request POST /users/get_current_user >
508hub_1 | └ functools.partial(<function _fix_request_current_app.<locals>.impl at 0x7f96ffe9d700>, handler=functools.partial(<function er...
509hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/web_middlewares.py", line 118, in impl
510hub_1 | return await handler(request)
511hub_1 | │ └ <Request POST /users/get_current_user >
512hub_1 | └ functools.partial(<function error_middleware at 0x7f9700abbb80>, handler=functools.partial(<function I18NHandler.get_middlewa...
513hub_1 | > File "/usr/local/lib/python3.8/site-packages/newcore/web.py", line 168, in error_middleware
514hub_1 | response = await handler(request)
515hub_1 | │ └ <Request POST /users/get_current_user >
516hub_1 | └ functools.partial(<function I18NHandler.get_middleware.<locals>.mw at 0x7f96ffe84f70>, handler=<bound method UserAccessAPI.ge...
517hub_1 | File "/usr/local/lib/python3.8/site-packages/newcore/i18n.py", line 165, in mw
518hub_1 | response = await handler(request)
519hub_1 | │ └ <Request POST /users/get_current_user >
520hub_1 | └ <bound method UserAccessAPI.get_current_user of <hub.access.UserAccessAPI object at 0x7f96ff5c70d0>>
521hub_1 |
522hub_1 | File "/bf-hub/hub/access.py", line 56, in get_current_user
523hub_1 | user, _ = await self._session_mgr.validate_session(auth_token)
524hub_1 | │ │ │ └ 'eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJ1c2VyX2lkIjoiZmU3MjNjNmEtYjNjZS00OGQ3LTgwY2YtOTU1NWQzY2FmZjFkIiwic2Vzc2lvbl9pZCI6Ijc...
525hub_1 | │ │ └ <function AbstractSessionManager.validate_session at 0x7f96ffe7eee0>
526hub_1 | │ └ <hub.session_manager.SessionManager object at 0x7f96ffe9bf40>
527hub_1 | └ <hub.access.UserAccessAPI object at 0x7f96ff5c70d0>
528hub_1 |
529hub_1 | File "/usr/local/lib/python3.8/site-packages/newcore/session_manager.py", line 77, in validate_session
530hub_1 | raise AuthenticationError("invalid token")
531hub_1 | └ <class 'newcore.exceptions.AuthenticationError'>
532hub_1 |
533hub_1 | newcore.exceptions.AuthenticationError: invalid token
534hub_1 | 2021-01-12 12:12:31.526 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /users/get_current_user HTTP/1.0" -> 500 [0.023559 sec]
535nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:31 +0000] "POST /api/hub/users/get_current_user HTTP/1.1" 500 99 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
536nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:31 +0000] "GET /favicon-32x32.png HTTP/1.1" 200 1699 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
537nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:34 +0000] "POST /api/hub/auth/login HTTP/1.1" 200 14 "http://localhost/login?redirect=%2Ffield-survey%2Fforest-site%2Ffe9c08fa-73b2-4def-add8-5d0d3a056d16%2Fsample-plot%2F1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
538hub_1 | 2021-01-12 12:12:34.562 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /auth/login HTTP/1.0" -> 200 [0.025267 sec]
539nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:34 +0000] "POST /api/hub/users/get_current_user HTTP/1.1" 200 79 "http://localhost/login?redirect=%2Ffield-survey%2Fforest-site%2Ffe9c08fa-73b2-4def-add8-5d0d3a056d16%2Fsample-plot%2F1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
540hub_1 | 2021-01-12 12:12:34.636 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /users/get_current_user HTTP/1.0" -> 200 [0.003914 sec]
541hub_1 | 2021-01-12 12:12:42.554 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /storage/create_storage_item HTTP/1.0" -> 200 [0.093546 sec]
542nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:42 +0000] "POST /api/hub/storage/create_storage_item HTTP/1.1" 200 58 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/449547a9-b13e-4a3d-a0f4-2a0a0d003dad" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
543nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:42 +0000] "GET /api/hub/uploads/upload_chunk?storage_id=4224ac07-0262-438f-bcbe-9853c9d720f5&storage_name=main&flowChunkNumber=1&flowChunkSize=20000000&flowCurrentChunkSize=2065&flowTotalSize=2065&flowIdentifier=2065-docker-composeyml&flowFilename=docker-compose.yml&flowRelativePath=docker-compose.yml&flowTotalChunks=1 HTTP/1.1" 204 0 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/449547a9-b13e-4a3d-a0f4-2a0a0d003dad" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
544hub_1 | 2021-01-12 12:12:42.571 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "GET /uploads/upload_chunk?storage_id=4224ac07-0262-438f-bcbe-9853c9d720f5&storage_name=main&flowChunkNumber=1&flowChunkSize=20000000&flowCurrentChunkSize=2065&flowTotalSize=2065&flowIdentifier=2065-docker-composeyml&flowFilename=docker-compose.yml&flowRelativePath=docker-compose.yml&flowTotalChunks=1 HTTP/1.0" -> 204 [0.008062 sec]
545nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:42 +0000] "POST /api/hub/pipelines/create_pipeline HTTP/1.1" 200 67 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/449547a9-b13e-4a3d-a0f4-2a0a0d003dad" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
546hub_1 | 2021-01-12 12:12:42.592 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /pipelines/create_pipeline HTTP/1.0" -> 200 [0.022156 sec]
547hub_1 | 2021-01-12 12:12:42.620 | DEBUG | aiohttp_sse_client.client:_dispatch_event:296 - MessageEvent(type=None, message='', data='{"storage_id": "4224ac07-0262-438f-bcbe-9853c9d720f5", "storage_name": "main", "file": "docker_compose.yml"}', origin='http://localhost', last_event_id='1610453562.619367')
548nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:42 +0000] "POST /api/hub/uploads/upload_chunk HTTP/1.1" 200 14 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/449547a9-b13e-4a3d-a0f4-2a0a0d003dad" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
549hub_1 | 2021-01-12 12:12:42.627 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /uploads/upload_chunk HTTP/1.0" -> 200 [0.043379 sec]
550hub_1 | 2021-01-12 12:12:47.717 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /pipelines/get_pipelines HTTP/1.0" -> 200 [0.004921 sec]
551nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:12:47 +0000] "POST /api/hub/pipelines/get_pipelines HTTP/1.1" 200 603 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
552hub_1 | 2021-01-12 12:13:26.125 | INFO | aiohttp.web_log:log:206 - 172.21.0.8 -> "POST /pipelines/get_pipelines HTTP/1.0" -> 200 [0.003108 sec]
553nginx_1 | 172.21.0.1 - - [12/Jan/2021:12:13:26 +0000] "POST /api/hub/pipelines/get_pipelines HTTP/1.1" 200 603 "http://localhost/field-survey/forest-site/fe9c08fa-73b2-4def-add8-5d0d3a056d16/sample-plot/1e8894f1-53d3-4737-84fe-c32270e7a9f9" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/87.0.4280.141 Safari/537.36" "-"
554hub_1 | 2021-01-12 12:14:27.620 | DEBUG | aiohttp_sse_client.client:close:257 - close
555hub_1 | 2021-01-12 12:14:27.622 | INFO | aiohttp.web_log:log:206 - 127.0.0.1 -> "GET /uploads/subscribe_upload_notifications HTTP/1.1" -> 200 [300.246002 sec]
556hub-rabbitmq_1 | 2021-01-12 12:14:27.628 [warning] <0.675.0> closing AMQP connection <0.675.0> (172.21.0.6:58892 -> 172.21.0.4:5672, vhost: '/', user: 'guest'):
557hub-rabbitmq_1 | client unexpectedly closed TCP connection
558hub_1 | 2021-01-12 12:14:27.625 | DEBUG | aiormq.connection:__reader:409 - Can not read bytes from server:
559hub_1 | Traceback (most recent call last):
560hub_1 |
561hub_1 | File "/usr/local/lib/python3.8/runpy.py", line 194, in _run_module_as_main
562hub_1 | return _run_code(code, main_globals, None,
563hub_1 | │ │ └ {'__name__': '__main__', '__doc__': None, '__package__': 'hub', '__loader__': <_frozen_importlib_external.SourceFileLoader ob...
564hub_1 | │ └ <code object <module> at 0x7f9701cc0660, file "/bf-hub/hub/__main__.py", line 1>
565hub_1 | └ <function _run_code at 0x7f97021420d0>
566hub_1 | File "/usr/local/lib/python3.8/runpy.py", line 87, in _run_code
567hub_1 | exec(code, run_globals)
568hub_1 | │ └ {'__name__': '__main__', '__doc__': None, '__package__': 'hub', '__loader__': <_frozen_importlib_external.SourceFileLoader ob...
569hub_1 | └ <code object <module> at 0x7f9701cc0660, file "/bf-hub/hub/__main__.py", line 1>
570hub_1 |
571hub_1 | File "/bf-hub/hub/__main__.py", line 47, in <module>
572hub_1 | entrypoint(prog_name="hub")
573hub_1 | └ <Command entrypoint>
574hub_1 |
575hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 829, in __call__
576hub_1 | return self.main(*args, **kwargs)
577hub_1 | │ │ │ └ {'prog_name': 'hub'}
578hub_1 | │ │ └ ()
579hub_1 | │ └ <function BaseCommand.main at 0x7f97015f2430>
580hub_1 | └ <Command entrypoint>
581hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 782, in main
582hub_1 | rv = self.invoke(ctx)
583hub_1 | │ │ └ <click.core.Context object at 0x7f96ffef24c0>
584hub_1 | │ └ <function Command.invoke at 0x7f97015f2dc0>
585hub_1 | └ <Command entrypoint>
586hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 1066, in invoke
587hub_1 | return ctx.invoke(self.callback, **ctx.params)
588hub_1 | │ │ │ │ │ └ {'configuration_file': None, 'log_level': 'debug', 'log_config': None, 'web_host': '0.0.0.0', 'web_port': 80, 'web_healthchec...
589hub_1 | │ │ │ │ └ <click.core.Context object at 0x7f96ffef24c0>
590hub_1 | │ │ │ └ <function build_entrypoint.<locals>.entrypoint at 0x7f96ffe84b80>
591hub_1 | │ │ └ <Command entrypoint>
592hub_1 | │ └ <function Context.invoke at 0x7f97015f1ee0>
593hub_1 | └ <click.core.Context object at 0x7f96ffef24c0>
594hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 610, in invoke
595hub_1 | return callback(*args, **kwargs)
596hub_1 | │ │ └ {'configuration_file': None, 'log_level': 'debug', 'log_config': None, 'web_host': '0.0.0.0', 'web_port': 80, 'web_healthchec...
597hub_1 | │ └ ()
598hub_1 | └ <function build_entrypoint.<locals>.entrypoint at 0x7f96ffe84b80>
599hub_1 | File "/usr/local/lib/python3.8/site-packages/cock.py", line 81, in entrypoint
600hub_1 | return main(config)
601hub_1 | │ └ {'configuration_file': None, 'log_level': 'debug', 'log_config': None, 'web_host': '0.0.0.0', 'web_port': 80, 'web_healthchec...
602hub_1 | └ <function main at 0x7f970010f940>
603hub_1 |
604hub_1 | File "/bf-hub/hub/__main__.py", line 34, in main
605hub_1 | asyncio.run(amain())
606hub_1 | │ │ └ <function amain at 0x7f97020dc3a0>
607hub_1 | │ └ <function run at 0x7f9701ce2a60>
608hub_1 | └ <module 'asyncio' from '/usr/local/lib/python3.8/asyncio/__init__.py'>
609hub_1 |
610hub_1 | File "/usr/local/lib/python3.8/asyncio/runners.py", line 44, in run
611hub_1 | return loop.run_until_complete(main)
612hub_1 | │ │ └ <coroutine object amain at 0x7f96ffed95c0>
613hub_1 | │ └ <function BaseEventLoop.run_until_complete at 0x7f970170ea60>
614hub_1 | └ <_UnixSelectorEventLoop running=True closed=False debug=False>
615hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 603, in run_until_complete
616hub_1 | self.run_forever()
617hub_1 | │ └ <function BaseEventLoop.run_forever at 0x7f970170e9d0>
618hub_1 | └ <_UnixSelectorEventLoop running=True closed=False debug=False>
619hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 570, in run_forever
620hub_1 | self._run_once()
621hub_1 | │ └ <function BaseEventLoop._run_once at 0x7f9701691550>
622hub_1 | └ <_UnixSelectorEventLoop running=True closed=False debug=False>
623hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 1859, in _run_once
624hub_1 | handle._run()
625hub_1 | │ └ <function Handle._run at 0x7f970175a310>
626hub_1 | └ <Handle <TaskWakeupMethWrapper object at 0x7f96ff5dd8e0>(<Future finished result=None>)>
627hub_1 | File "/usr/local/lib/python3.8/asyncio/events.py", line 81, in _run
628hub_1 | self._context.run(self._callback, *self._args)
629hub_1 | │ │ │ │ │ └ <member '_args' of 'Handle' objects>
630hub_1 | │ │ │ │ └ <Handle <TaskWakeupMethWrapper object at 0x7f96ff5dd8e0>(<Future finished result=None>)>
631hub_1 | │ │ │ └ <member '_callback' of 'Handle' objects>
632hub_1 | │ │ └ <Handle <TaskWakeupMethWrapper object at 0x7f96ff5dd8e0>(<Future finished result=None>)>
633hub_1 | │ └ <member '_context' of 'Handle' objects>
634hub_1 | └ <Handle <TaskWakeupMethWrapper object at 0x7f96ff5dd8e0>(<Future finished result=None>)>
635hub_1 | > File "/usr/local/lib/python3.8/site-packages/aiormq/connection.py", line 375, in __reader
636hub_1 | weight, channel, frame = await self.__receive_frame()
637hub_1 | │ │ └ <Connection: "ampq://guest:******@hub-rabbitmq:5672">
638hub_1 | │ └ 0
639hub_1 | └ 8
640hub_1 | File "/usr/local/lib/python3.8/site-packages/aiormq/connection.py", line 327, in __receive_frame
641hub_1 | frame_header = await self.reader.readexactly(1)
642hub_1 | │ └ None
643hub_1 | └ <Connection: "ampq://guest:******@hub-rabbitmq:5672">
644hub_1 | File "/usr/local/lib/python3.8/asyncio/streams.py", line 721, in readexactly
645hub_1 | raise exceptions.IncompleteReadError(incomplete, n)
646hub_1 | │ │ │ └ 1
647hub_1 | │ │ └ b''
648hub_1 | │ └ <class 'asyncio.exceptions.IncompleteReadError'>
649hub_1 | └ <module 'asyncio.exceptions' from '/usr/local/lib/python3.8/asyncio/exceptions.py'>
650hub_1 |
651hub_1 | asyncio.exceptions.IncompleteReadError: 0 bytes read on a total of 1 expected bytes
652hub_1 | 2021-01-12 12:14:27.635 | DEBUG | aio_pika.connection:_on_connection_close:102 - Closing AMQP connection None
653hub_1 | Traceback (most recent call last):
654hub_1 | File "/usr/local/lib/python3.8/runpy.py", line 194, in _run_module_as_main
655hub_1 | return _run_code(code, main_globals, None,
656hub_1 | File "/usr/local/lib/python3.8/runpy.py", line 87, in _run_code
657hub_1 | exec(code, run_globals)
658hub_1 | File "/bf-hub/hub/__main__.py", line 47, in <module>
659hub_1 | entrypoint(prog_name="hub")
660hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 829, in __call__
661hub_1 | return self.main(*args, **kwargs)
662hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 782, in main
663hub_1 | rv = self.invoke(ctx)
664hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 1066, in invoke
665hub_1 | return ctx.invoke(self.callback, **ctx.params)
666hub_1 | File "/usr/local/lib/python3.8/site-packages/click/core.py", line 610, in invoke
667hub_1 | return callback(*args, **kwargs)
668hub_1 | File "/usr/local/lib/python3.8/site-packages/cock.py", line 81, in entrypoint
669hub_1 | return main(config)
670hub_1 | File "/bf-hub/hub/__main__.py", line 34, in main
671hub_1 | asyncio.run(amain())
672hub_1 | File "/usr/local/lib/python3.8/asyncio/runners.py", line 44, in run
673hub_1 | return loop.run_until_complete(main)
674hub_1 | File "/usr/local/lib/python3.8/asyncio/base_events.py", line 616, in run_until_complete
675hub_1 | return future.result()
676hub_1 | File "/bf-hub/hub/__main__.py", line 22, in amain
677hub_1 | await WebApplicationService().run()
678hub_1 | File "/usr/local/lib/python3.8/site-packages/facet.py", line 99, in run
679hub_1 | await self.wait()
680hub_1 | File "/usr/local/lib/python3.8/site-packages/facet.py", line 95, in wait
681hub_1 | await self.__exit_point
682hub_1 | File "/usr/local/lib/python3.8/site-packages/pipeliner/uploader.py", line 53, in watch
683hub_1 | async for event in event_source:
684hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp_sse_client/client.py", line 148, in __anext__
685hub_1 | async for line_in_bytes in self._response.content:
686hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/streams.py", line 39, in __anext__
687hub_1 | rv = await self.read_func()
688hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/streams.py", line 338, in readline
689hub_1 | await self._wait("readline")
690hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/streams.py", line 306, in _wait
691hub_1 | await waiter
692hub_1 | File "/usr/local/lib/python3.8/site-packages/aiohttp/helpers.py", line 656, in __exit__
693hub_1 | raise asyncio.TimeoutError from None
694hub_1 | asyncio.exceptions.TimeoutError
695docker-dev_hub_1 exited with code 1