expert
expert copied to clipboard
elixir-lang
Failed to start with `{:error, :start_timeout}` error
First of all, congratulations on this excellent work! This is the kind of thing that we all need and everyone is super excited about. 🙌
I am running Linux (amd64) and using mise to setup Erlang, Elixir and Zig.
❯ mise current
zig 0.14.1
elixir 1.18.4-otp-28
erlang 28.0.2
node 24.7.0
- I've built from source using the
just release-localcommand (commit hashb1fb0225de89cd07c8b6ed954aa3d1771850e5e1). - Installed Lexical extension on Visual Studio Code.
- In the Lexical settings I've pointed to the built binary.
- Created a new Elixir project with
mix new my_project.
When opening Visual Studio Code, the language server does not seem to start. The engine gets built but when connecting to it there seems to be some kind of failure causing a {:error, :start_timeout}
14:31:32.138 [debug] handled request client -> server initialize in 62ms
14:31:32.153 [info] Finding or building engine for project my_project
14:32:05.538 [info] Engine build available at: /home/daniel/.local/share/Expert/0.1.0/elixir-1.18.4-erts-16.0.2/70d9f741f61a6eade6974390384e6236/_build/dev_ns
14:32:05.585 [info] Port #Port<0.13> has exited due to: :normal
14:32:08.564 [error] GenServer :"my_project::node_process" terminating
** (stop) :start_timeout
Last message: :maybe_start_timeout
State: nil
14:32:08.564 [error] Process #PID<0.173.0> terminating
** (exit) {:error, :start_timeout}
(stdlib 7.0.2) gen_server.erl:2257: :gen_server.init_it/6
(stdlib 7.0.2) proc_lib.erl:333: :proc_lib.init_p_do_apply/3
Initial Call: XPExpert.Project.Node.init/1
Lexical output
2025-08-29 14:31:26.907 [info] Release override path set to "/home/daniel/Developer/expert/apps/expert/burrito_out/expert_linux_amd64". Skipping auto-install.
2025-08-29 14:31:26.907 [info] Starting Lexical in directory /home/daniel/Developer/my_project
2025-08-29 14:31:26.907 [info] Starting lexical release in "/home/daniel/Developer/expert/apps/expert/burrito_out/expert_linux_amd64"
2025-08-29 14:31:26.907 [info] [Trace - 2:31:26 PM] Sending request 'initialize - (0)'.
2025-08-29 14:31:32.116 [info] [Trace - 2:31:32 PM] Received request 'window/workDoneProgress/create - (1)'.
2025-08-29 14:31:32.116 [info] [Trace - 2:31:32 PM] Sending response 'window/workDoneProgress/create - (1)'. Processing request took 0ms
2025-08-29 14:31:32.121 [info] [Trace - 2:31:32 PM] Received response 1 without active response promise.
2025-08-29 14:31:32.139 [info] [Trace - 2:31:32 PM] Received response 'initialize - (0)' in 5240ms.
2025-08-29 14:31:32.140 [info] [Trace - 2:31:32 PM] Sending notification 'initialized'.
2025-08-29 14:31:32.145 [info] [Trace - 2:31:32 PM] Sending notification 'textDocument/didOpen'.
2025-08-29 14:31:32.149 [info] [Trace - 2:31:32 PM] Sending request 'textDocument/documentSymbol - (1)'.
2025-08-29 14:31:33.262 [info] [Trace - 2:31:33 PM] Sending request 'textDocument/documentSymbol - (2)'.
2025-08-29 14:31:33.263 [info] [Trace - 2:31:33 PM] Sending request 'textDocument/codeLens - (3)'.
2025-08-29 14:31:33.291 [info] [Trace - 2:31:33 PM] Sending notification '$/cancelRequest'.
2025-08-29 14:31:33.545 [info] [Trace - 2:31:33 PM] Sending request 'textDocument/codeLens - (4)'.
2025-08-29 14:31:38.004 [info] [Trace - 2:31:38 PM] Sending notification '$/cancelRequest'.
2025-08-29 14:31:38.005 [info] [Trace - 2:31:38 PM] Sending request 'textDocument/codeLens - (5)'.
2025-08-29 14:32:03.207 [info] [Trace - 2:32:03 PM] Sending notification '$/cancelRequest'.
2025-08-29 14:32:03.209 [info] [Trace - 2:32:03 PM] Sending request 'textDocument/codeLens - (6)'.
2025-08-29 14:32:05.585 [info] env: ‘bash’: No such file or directory
2025-08-29 14:32:15.200 [info] [Trace - 2:32:15 PM] Sending notification '$/cancelRequest'.
2025-08-29 14:32:15.201 [info] [Trace - 2:32:15 PM] Sending request 'textDocument/codeLens - (7)'.
2025-08-29 14:32:16.665 [info] [Trace - 2:32:16 PM] Sending request 'textDocument/codeAction - (8)'.
2025-08-29 14:32:17.037 [info] [Trace - 2:32:17 PM] Sending notification 'textDocument/didChange'.
2025-08-29 14:32:17.037 [info] [Trace - 2:32:17 PM] Sending notification '$/cancelRequest'.
2025-08-29 14:32:17.091 [info] [Trace - 2:32:17 PM] Sending notification '$/cancelRequest'.
2025-08-29 14:32:17.327 [info] [Trace - 2:32:17 PM] Sending notification 'textDocument/didChange'.
2025-08-29 14:32:17.338 [info] [Trace - 2:32:17 PM] Sending request 'textDocument/completion - (9)'.
2025-08-29 14:32:17.576 [info] [Trace - 2:32:17 PM] Sending request 'textDocument/codeAction - (10)'.
2025-08-29 14:32:17.625 [info] [Trace - 2:32:17 PM] Sending notification '$/cancelRequest'.
Complete output of .expert/expert.log
14:31:32.038 [debug] Child {XPLoggerFileBackend, :general_log} of Supervisor Logger.Backends.Supervisor started
Pid: #PID<0.103.0>
Start Call: Logger.Backends.Watcher.start_link({{XPLoggerFileBackend, :general_log}, {XPLoggerFileBackend, :general_log}})
Restart: :transient
Shutdown: 5000
Type: :worker
14:31:32.038 [info] Child Logger.Backends.Supervisor of Supervisor Logger.Backends.Internal started
Pid: #PID<0.102.0>
Start Call: Logger.Backends.Supervisor.start_link([{XPLoggerFileBackend, :general_log}])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.038 [info] Child Logger.Backends.Internal of Supervisor Logger.Supervisor started
Pid: #PID<0.98.0>
Start Call: Logger.Backends.Internal.start_link([])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.038 [info] Application logger started at :nonode@nohost
14:31:32.038 [info] Application xp_mime started at :nonode@nohost
14:31:32.038 [info] Application xp_hpax started at :nonode@nohost
14:31:32.038 [info] Application crypto started at :nonode@nohost
14:31:32.038 [info] Application asn1 started at :nonode@nohost
14:31:32.038 [info] Application public_key started at :nonode@nohost
14:31:32.039 [debug] Child :ssl_handler of Supervisor :logger_sup started
Pid: #PID<0.108.0>
Start Call: :logger_olp.start_link(:logger_std_h_ssl_handler, :logger_h_common, %{id: :ssl_handler, module: :logger_std_h, config: %{type: :standard_io, filesync_repeat_interval: :no_repeat}, level: :debug, filters: [], filter_default: :stop, formatter: {:ssl_logger, %{}}}, %{})
Restart: :temporary
Shutdown: 2000
Type: :worker
14:31:32.041 [info] Child :ssl_pem_cache of Supervisor :ssl_admin_sup started
Pid: #PID<0.112.0>
Start Call: :ssl_pem_cache.start_link([])
Restart: :permanent
Shutdown: 4000
Type: :worker
14:31:32.043 [info] Child :ssl_manager of Supervisor :ssl_admin_sup started
Pid: #PID<0.113.0>
Start Call: :ssl_manager.start_link([])
Restart: :permanent
Shutdown: 4000
Type: :worker
14:31:32.044 [info] Child :tls_client_ticket_store of Supervisor :ssl_admin_sup started
Pid: #PID<0.114.0>
Start Call: :tls_client_ticket_store.start_link(1000, 7200)
Restart: :permanent
Shutdown: 4000
Type: :worker
14:31:32.044 [info] Child :ssl_admin_sup of Supervisor :ssl_sup started
Pid: #PID<0.111.0>
Start Call: :ssl_admin_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.044 [info] Child :tls_connection_sup of Supervisor :tls_sup started
Pid: #PID<0.117.0>
Start Call: :tls_connection_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.045 [info] Child :ssl_listen_tracker_sup of Supervisor :tls_server_sup started
Pid: #PID<0.119.0>
Start Call: :ssl_listen_tracker_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.045 [info] Child :tls_server_session_ticket of Supervisor :tls_server_sup started
Pid: #PID<0.120.0>
Start Call: :tls_server_session_ticket_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.045 [info] Child :ssl_server_session_cache_sup of Supervisor :tls_server_sup started
Pid: #PID<0.121.0>
Start Call: :ssl_server_session_cache_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.046 [info] Child :ssl_upgrade_server_session_cache_sup of Supervisor :tls_server_sup started
Pid: #PID<0.122.0>
Start Call: :ssl_upgrade_server_session_cache_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.046 [info] Child :tls_server_sup of Supervisor :tls_sup started
Pid: #PID<0.118.0>
Start Call: :tls_server_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.046 [info] Child :tls_sup of Supervisor :ssl_connection_sup started
Pid: #PID<0.116.0>
Start Call: :tls_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.047 [info] Child :dtls_connection_sup of Supervisor :dtls_sup started
Pid: #PID<0.124.0>
Start Call: :dtls_connection_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.047 [info] Child :dtls_listener_sup of Supervisor :dtls_server_sup started
Pid: #PID<0.126.0>
Start Call: :dtls_listener_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.048 [info] Child :dtls_server_session_cache_sup of Supervisor :dtls_server_sup started
Pid: #PID<0.127.0>
Start Call: :dtls_server_session_cache_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.048 [info] Child :dtls_server_sup of Supervisor :dtls_sup started
Pid: #PID<0.125.0>
Start Call: :dtls_server_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.048 [info] Child :dtls_sup of Supervisor :ssl_connection_sup started
Pid: #PID<0.123.0>
Start Call: :dtls_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.048 [info] Child :ssl_connection_sup of Supervisor :ssl_sup started
Pid: #PID<0.115.0>
Start Call: :ssl_connection_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
14:31:32.048 [info] Application ssl started at :nonode@nohost
14:31:32.048 [info] Application xp_mint started at :nonode@nohost
14:31:32.048 [info] Application xp_nimble_options started at :nonode@nohost
14:31:32.050 [info] Child XPNimblePool.TaskSupervisor of Supervisor #PID<0.133.0> (Supervisor.Default) started
Pid: #PID<0.134.0>
Start Call: Task.Supervisor.start_link([name: XPNimblePool.TaskSupervisor])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.050 [info] Application xp_nimble_pool started at :nonode@nohost
14:31:32.050 [info] Child :telemetry_handler_table of Supervisor :telemetry_sup started
Pid: #PID<0.138.0>
Start Call: :telemetry_handler_table.start_link()
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.051 [info] Application xp_telemetry started at :nonode@nohost
14:31:32.051 [info] Application xp_finch started at :nonode@nohost
14:31:32.059 [info] Child XPReq.Finch.PIDPartition0 of Supervisor XPReq.Finch started
Pid: #PID<0.144.0>
Start Call: Registry.Partition.start_link(XPReq.Finch.PIDPartition0, {:duplicate, XPReq.Finch, 0, 1, XPReq.Finch.KeyPartition0, XPReq.Finch.PIDPartition0, [], false})
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.059 [info] Child XPReq.Finch of Supervisor XPReq.Finch.Supervisor started
Pid: #PID<0.143.0>
Start Call: Registry.start_link([keys: :duplicate, name: XPReq.Finch, meta: [config: %{pools: %{}, registry_name: XPReq.Finch, supervisor_name: XPReq.Finch.PoolSupervisor, manager_name: XPReq.Finch.PoolManager, default_pool_config: %{count: 1, size: 50, mod: XPFinch.HTTP1.Pool, conn_opts: [protocols: [:http1], transport_opts: [keepalive: true, nodelay: true, timeout: 5000], ssl_key_log_file_device: nil], pool_max_idle_time: :infinity, conn_max_idle_time: :infinity, start_pool_metrics?: false}}]])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.059 [info] Child XPReq.Finch.PoolSupervisor of Supervisor XPReq.Finch.Supervisor started
Pid: #PID<0.145.0>
Start Call: DynamicSupervisor.start_link([name: XPReq.Finch.PoolSupervisor, strategy: :one_for_one])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.059 [info] Child XPFinch.PoolManager of Supervisor XPReq.Finch.Supervisor started
Pid: #PID<0.146.0>
Start Call: XPFinch.PoolManager.start_link(%{pools: %{}, registry_name: XPReq.Finch, supervisor_name: XPReq.Finch.PoolSupervisor, manager_name: XPReq.Finch.PoolManager, default_pool_config: %{count: 1, size: 50, mod: XPFinch.HTTP1.Pool, conn_opts: [protocols: [:http1], transport_opts: [keepalive: true, nodelay: true, timeout: 5000], ssl_key_log_file_device: nil], pool_max_idle_time: :infinity, conn_max_idle_time: :infinity, start_pool_metrics?: false}})
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.059 [info] Child XPReq.Finch of Supervisor #PID<0.141.0> (Supervisor.Default) started
Pid: #PID<0.142.0>
Start Call: XPFinch.start_link([name: XPReq.Finch, pools: %{default: [protocols: [:http1]]}])
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.059 [info] Child XPReq.FinchSupervisor of Supervisor #PID<0.141.0> (Supervisor.Default) started
Pid: #PID<0.147.0>
Start Call: DynamicSupervisor.start_link([strategy: :one_for_one, name: XPReq.FinchSupervisor])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.059 [info] Child XPReq.Test.Ownership of Supervisor #PID<0.141.0> (Supervisor.Default) started
Pid: #PID<0.148.0>
Start Call: XPReq.Test.Ownership.start_link([name: XPReq.Test.Ownership])
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.059 [info] Application xp_req started at :nonode@nohost
14:31:32.059 [info] Application burrito started at :nonode@nohost
14:31:32.059 [info] Application erts started at :nonode@nohost
14:31:32.059 [info] Application xp_schematic started at :nonode@nohost
14:31:32.059 [info] Application xp_typed_struct started at :nonode@nohost
14:31:32.059 [info] Application xp_gen_lsp started at :nonode@nohost
14:31:32.063 [info] Child XPSnowflake.Generator of Supervisor #PID<0.151.0> (Supervisor.Default) started
Pid: #PID<0.152.0>
Start Call: XPSnowflake.Generator.start_link(0, 1023)
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.063 [info] Application xp_snowflake started at :nonode@nohost
14:31:32.063 [info] Application xp_sourceror started at :nonode@nohost
14:31:32.063 [info] Application xp_forge started at :nonode@nohost
14:31:32.063 [info] Application xp_logger_file_backend started at :nonode@nohost
14:31:32.065 [info] Child :ttb_autostart of Supervisor :runtime_tools_sup started
Pid: #PID<0.156.0>
Start Call: :ttb_autostart.start_link()
Restart: :temporary
Shutdown: 3000
Type: :worker
14:31:32.065 [info] Application runtime_tools started at :nonode@nohost
14:31:32.065 [info] Application et started at :nonode@nohost
14:31:32.065 [info] Application observer started at :nonode@nohost
14:31:32.065 [info] Application xp_path_glob started at :nonode@nohost
14:31:32.068 [info] Child XPForge.Document.Store of Supervisor XPExpert.Supervisor started
Pid: #PID<0.160.0>
Start Call: XPForge.Document.Store.start_link([derive: [analysis: &XPForge.Ast.analyze/1]])
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.069 [info] Child XPExpert.ProjectSupervisor of Supervisor XPExpert.Supervisor started
Pid: #PID<0.161.0>
Start Call: DynamicSupervisor.start_link([name: XPExpert.ProjectSupervisor, strategy: :one_for_one])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.069 [info] Child XPExpert.DynamicSupervisor of Supervisor XPExpert.Supervisor started
Pid: #PID<0.162.0>
Start Call: DynamicSupervisor.start_link([name: XPExpert.DynamicSupervisor])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.069 [info] Child XPGenLSP.Assigns of Supervisor XPExpert.Supervisor started
Pid: #PID<0.163.0>
Start Call: XPGenLSP.Assigns.start_link([name: XPExpert.Assigns])
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.069 [info] Child :expert_task_queue of Supervisor XPExpert.Supervisor started
Pid: #PID<0.164.0>
Start Call: Task.Supervisor.start_link([name: :expert_task_queue])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
14:31:32.070 [info] Child XPGenLSP.Buffer of Supervisor XPExpert.Supervisor started
Pid: #PID<0.165.0>
Start Call: XPGenLSP.Buffer.start_link([name: XPExpert.Buffer])
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.070 [debug] [name: XPExpert, buffer: XPExpert.Buffer, task_supervisor: :expert_task_queue, dynamic_supervisor: XPExpert.DynamicSupervisor, assigns: XPExpert.Assigns]
14:31:32.072 [info] Child XPExpert of Supervisor XPExpert.Supervisor started
Pid: #PID<0.166.0>
Start Call: XPExpert.start_link([name: XPExpert, buffer: XPExpert.Buffer, task_supervisor: :expert_task_queue, dynamic_supervisor: XPExpert.DynamicSupervisor, assigns: XPExpert.Assigns])
Restart: :permanent
Shutdown: 500
Type: :worker
14:31:32.072 [info] Application xp_expert started at :nonode@nohost
14:31:32.105 [info] Starting project at uri file:///home/daniel/Developer/my_project
14:31:32.110 [info] Child {XPExpert.Project.Progress, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.171.0>
Start Call: XPExpert.Project.Progress.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 16260})
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.110 [info] Child {XPExpert.EngineSupervisor, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.172.0>
Start Call: XPExpert.EngineSupervisor.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 16260})
Restart: :permanent
Shutdown: 5000
Type: :worker
14:31:32.112 [debug] sent request server -> client window/workDoneProgress/create
14:31:32.116 [info] Child :erl_epmd of Supervisor :net_sup started
Pid: #PID<0.175.0>
Start Call: :erl_epmd.start_link()
Restart: :permanent
Shutdown: 2000
Type: :worker
14:31:32.117 [info] Child :auth of Supervisor :net_sup started
Pid: #PID<0.176.0>
Start Call: :auth.start_link()
Restart: :permanent
Shutdown: 2000
Type: :worker
14:31:32.118 [error] ** (XPGenLSP.InvalidRequest) Invalid request from the client
Received: %{"error" => %{"code" => -32601, "message" => "Unhandled method window/workDoneProgress/create"}, "id" => 1, "jsonrpc" => "2.0"}
Errors: "unexpected request payload"
14:31:32.119 [info] Child :net_kernel of Supervisor :net_sup started
Pid: #PID<0.177.0>
Start Call: :net_kernel.start_link(%{name: :"[email protected]", supervisor: :net_sup_dynamic, name_domain: :longnames, clean_halt: false})
Restart: :permanent
Shutdown: 2000
Type: :worker
14:31:32.119 [debug] Child :net_sup_dynamic of Supervisor :kernel_sup started
Pid: #PID<0.174.0>
Start Call: :erl_distribution.start_link(%{name: :"[email protected]", supervisor: :net_sup_dynamic, name_domain: :longnames, clean_halt: false})
Restart: :permanent
Shutdown: 1000
Type: :supervisor
14:31:32.138 [debug] handled request client -> server initialize in 62ms
14:31:32.153 [info] Finding or building engine for project my_project
14:32:05.538 [info] Engine build available at: /home/daniel/.local/share/Expert/0.1.0/elixir-1.18.4-erts-16.0.2/70d9f741f61a6eade6974390384e6236/_build/dev_ns
14:32:05.585 [info] Port #Port<0.13> has exited due to: :normal
14:32:08.564 [error] GenServer :"my_project::node_process" terminating
** (stop) :start_timeout
Last message: :maybe_start_timeout
State: nil
14:32:08.564 [error] Process #PID<0.173.0> terminating
** (exit) {:error, :start_timeout}
(stdlib 7.0.2) gen_server.erl:2257: :gen_server.init_it/6
(stdlib 7.0.2) proc_lib.erl:333: :proc_lib.init_p_do_apply/3
Initial Call: XPExpert.Project.Node.init/1
Ancestors: [:"my_project::supervisor", XPExpert.ProjectSupervisor, XPExpert.Supervisor, #PID<0.158.0>]
Message Queue Length: 0
Messages: []
Links: [#PID<0.170.0>]
Dictionary: []
Trapping Exits: false
Status: :running
Heap Size: 46422
Stack Size: 29
Reductions: 1125323
14:32:08.564 [error] Process :"my_project::node_process" (#PID<0.181.0>) terminating
** (exit) :start_timeout
(stdlib 7.0.2) gen_server.erl:2562: :gen_server.handle_common_reply/5
(stdlib 7.0.2) proc_lib.erl:333: :proc_lib.init_p_do_apply/3
Initial Call: XPExpert.EngineNode.init/1
Ancestors: [XPExpert.EngineSupervisor, :"my_project::supervisor", XPExpert.ProjectSupervisor, XPExpert.Supervisor, #PID<0.158.0>]
Message Queue Length: 0
Messages: []
Links: [#PID<0.172.0>]
Dictionary: []
Trapping Exits: true
Status: :running
Heap Size: 1598
Stack Size: 29
Reductions: 19935
14:32:08.564 [error] Child :undefined of Supervisor XPExpert.EngineSupervisor terminated
** (exit) :start_timeout
Pid: #PID<0.181.0>
Start Call: XPExpert.EngineNode.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 16260})
Restart: :transient
Shutdown: 5000
Type: :worker
14:32:08.564 [error] Child {XPExpert.Project.Node, "my_project"} of Supervisor :"my_project::supervisor" failed to start
** (exit) {:error, :start_timeout}
Start Call: XPExpert.Project.Node.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 16260})
Restart: :permanent
Shutdown: 5000
Type: :worker
2025-08-29 14:32:03.209 [info] [Trace - 2:32:03 PM] Sending request 'textDocument/codeLens - (6)'.
2025-08-29 14:32:05.585 [info] env: ‘bash’: No such file or directory
2025-08-29 14:32:15.200 [info] [Trace - 2:32:15 PM] Sending notification '$/cancelRequest'.
What does mise env -s bash do in your shell?
What does
mise env -s bashdo in your shell?
❯ mise env -s bash
export PATH='/home/daniel/.local/share/mise/installs/elixir/1.18.4-otp-28/bin:/home/daniel/.local/share/mise/installs/elixir/1.18.4-otp-28/.mix/escripts:/home/daniel/.local/share/mise/installs/erlang/28.0.2/bin:/home/daniel/.local/share/mise/installs/node/24.7.0/bin:./bin:/home/daniel/.local/bin:/home/daniel/.local/share/omarchy/bin/:/usr/local/sbin:/usr/local/bin:/usr/bin'
It seems to be setting the PATH correctly.
Can you add a Logger.info that prints what _data is here? https://github.com/elixir-lang/expert/blob/main/apps/expert/lib/expert/engine_node.ex#L227
That should give us more info on why it's failing to install
Also add MIX_ENV=dev when building expert to enable debug logs and bypass burrito caching
Also a log for _message here: https://github.com/elixir-lang/expert/blob/main/apps/expert/lib/expert/engine_node.ex#L345
Probably yeah, they can get quite noisy while the engine is building but they're still valuable nonetheless
I've added the logs and compiled with MIX_ENV=dev and now something else is happening. It seems the engine now starts because the .expert directory now contains more stuff (build/, indexes/, expert.log and project.log). But I still don't get any intellisense suggestions and other stuff.
On the logs I see the following:
23:22:53.687 [info] Received unexpected port message: ~c"\n23:22:53.687 [info] Build path /home/daniel/Developer/my_project/.expert/build/erl-28/elixir-1.18 was compiled on a previous erlang version. Deleting\n"
Maybe an issue with the latest versions of Erlang and Elixir?
Full export.log with debug logs
23:22:21.260 [debug] Child {XPLoggerFileBackend, :general_log} of Supervisor Logger.Backends.Supervisor started
Pid: #PID<0.103.0>
Start Call: Logger.Backends.Watcher.start_link({{XPLoggerFileBackend, :general_log}, {XPLoggerFileBackend, :general_log}})
Restart: :transient
Shutdown: 5000
Type: :worker
23:22:21.260 [info] Child Logger.Backends.Supervisor of Supervisor Logger.Backends.Internal started
Pid: #PID<0.102.0>
Start Call: Logger.Backends.Supervisor.start_link([{XPLoggerFileBackend, :general_log}])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.260 [info] Child Logger.Backends.Internal of Supervisor Logger.Supervisor started
Pid: #PID<0.98.0>
Start Call: Logger.Backends.Internal.start_link([])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.260 [info] Application logger started at :nonode@nohost
23:22:21.260 [info] Application xp_mime started at :nonode@nohost
23:22:21.260 [info] Application xp_hpax started at :nonode@nohost
23:22:21.260 [info] Application crypto started at :nonode@nohost
23:22:21.260 [info] Application asn1 started at :nonode@nohost
23:22:21.260 [info] Application public_key started at :nonode@nohost
23:22:21.261 [debug] Child :ssl_handler of Supervisor :logger_sup started
Pid: #PID<0.108.0>
Start Call: :logger_olp.start_link(:logger_std_h_ssl_handler, :logger_h_common, %{id: :ssl_handler, module: :logger_std_h, config: %{type: :standard_io, filesync_repeat_interval: :no_repeat}, level: :debug, filter_default: :stop, filters: [], formatter: {:ssl_logger, %{}}}, %{})
Restart: :temporary
Shutdown: 2000
Type: :worker
23:22:21.262 [info] Child :ssl_pem_cache of Supervisor :ssl_admin_sup started
Pid: #PID<0.112.0>
Start Call: :ssl_pem_cache.start_link([])
Restart: :permanent
Shutdown: 4000
Type: :worker
23:22:21.264 [info] Child :ssl_manager of Supervisor :ssl_admin_sup started
Pid: #PID<0.113.0>
Start Call: :ssl_manager.start_link([])
Restart: :permanent
Shutdown: 4000
Type: :worker
23:22:21.264 [info] Child :tls_client_ticket_store of Supervisor :ssl_admin_sup started
Pid: #PID<0.114.0>
Start Call: :tls_client_ticket_store.start_link(1000, 7200)
Restart: :permanent
Shutdown: 4000
Type: :worker
23:22:21.264 [info] Child :ssl_admin_sup of Supervisor :ssl_sup started
Pid: #PID<0.111.0>
Start Call: :ssl_admin_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :tls_connection_sup of Supervisor :tls_sup started
Pid: #PID<0.117.0>
Start Call: :tls_connection_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :ssl_listen_tracker_sup of Supervisor :tls_server_sup started
Pid: #PID<0.119.0>
Start Call: :ssl_listen_tracker_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :tls_server_session_ticket of Supervisor :tls_server_sup started
Pid: #PID<0.120.0>
Start Call: :tls_server_session_ticket_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :ssl_server_session_cache_sup of Supervisor :tls_server_sup started
Pid: #PID<0.121.0>
Start Call: :ssl_server_session_cache_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :ssl_upgrade_server_session_cache_sup of Supervisor :tls_server_sup started
Pid: #PID<0.122.0>
Start Call: :ssl_upgrade_server_session_cache_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :tls_server_sup of Supervisor :tls_sup started
Pid: #PID<0.118.0>
Start Call: :tls_server_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.265 [info] Child :tls_sup of Supervisor :ssl_connection_sup started
Pid: #PID<0.116.0>
Start Call: :tls_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Child :dtls_connection_sup of Supervisor :dtls_sup started
Pid: #PID<0.124.0>
Start Call: :dtls_connection_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Child :dtls_listener_sup of Supervisor :dtls_server_sup started
Pid: #PID<0.126.0>
Start Call: :dtls_listener_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Child :dtls_server_session_cache_sup of Supervisor :dtls_server_sup started
Pid: #PID<0.127.0>
Start Call: :dtls_server_session_cache_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Child :dtls_server_sup of Supervisor :dtls_sup started
Pid: #PID<0.125.0>
Start Call: :dtls_server_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Child :dtls_sup of Supervisor :ssl_connection_sup started
Pid: #PID<0.123.0>
Start Call: :dtls_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Child :ssl_connection_sup of Supervisor :ssl_sup started
Pid: #PID<0.115.0>
Start Call: :ssl_connection_sup.start_link()
Restart: :permanent
Shutdown: 4000
Type: :supervisor
23:22:21.266 [info] Application ssl started at :nonode@nohost
23:22:21.267 [info] Application xp_mint started at :nonode@nohost
23:22:21.267 [info] Application xp_nimble_options started at :nonode@nohost
23:22:21.267 [info] Child XPNimblePool.TaskSupervisor of Supervisor #PID<0.133.0> (Supervisor.Default) started
Pid: #PID<0.134.0>
Start Call: Task.Supervisor.start_link([name: XPNimblePool.TaskSupervisor])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.268 [info] Application xp_nimble_pool started at :nonode@nohost
23:22:21.268 [info] Child :telemetry_handler_table of Supervisor :telemetry_sup started
Pid: #PID<0.138.0>
Start Call: :telemetry_handler_table.start_link()
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.268 [info] Application xp_telemetry started at :nonode@nohost
23:22:21.268 [info] Application xp_finch started at :nonode@nohost
23:22:21.274 [info] Child XPReq.Finch.PIDPartition0 of Supervisor XPReq.Finch started
Pid: #PID<0.144.0>
Start Call: Registry.Partition.start_link(XPReq.Finch.PIDPartition0, {:duplicate, XPReq.Finch, 0, 1, XPReq.Finch.KeyPartition0, XPReq.Finch.PIDPartition0, [], false})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.274 [info] Child XPReq.Finch of Supervisor XPReq.Finch.Supervisor started
Pid: #PID<0.143.0>
Start Call: Registry.start_link([keys: :duplicate, name: XPReq.Finch, meta: [config: %{pools: %{}, registry_name: XPReq.Finch, supervisor_name: XPReq.Finch.PoolSupervisor, manager_name: XPReq.Finch.PoolManager, default_pool_config: %{count: 1, size: 50, mod: XPFinch.HTTP1.Pool, conn_opts: [protocols: [:http1], transport_opts: [keepalive: true, nodelay: true, timeout: 5000], ssl_key_log_file_device: nil], pool_max_idle_time: :infinity, conn_max_idle_time: :infinity, start_pool_metrics?: false}}]])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.274 [info] Child XPReq.Finch.PoolSupervisor of Supervisor XPReq.Finch.Supervisor started
Pid: #PID<0.145.0>
Start Call: DynamicSupervisor.start_link([name: XPReq.Finch.PoolSupervisor, strategy: :one_for_one])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.274 [info] Child XPFinch.PoolManager of Supervisor XPReq.Finch.Supervisor started
Pid: #PID<0.146.0>
Start Call: XPFinch.PoolManager.start_link(%{pools: %{}, registry_name: XPReq.Finch, supervisor_name: XPReq.Finch.PoolSupervisor, manager_name: XPReq.Finch.PoolManager, default_pool_config: %{count: 1, size: 50, mod: XPFinch.HTTP1.Pool, conn_opts: [protocols: [:http1], transport_opts: [keepalive: true, nodelay: true, timeout: 5000], ssl_key_log_file_device: nil], pool_max_idle_time: :infinity, conn_max_idle_time: :infinity, start_pool_metrics?: false}})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.274 [info] Child XPReq.Finch of Supervisor #PID<0.141.0> (Supervisor.Default) started
Pid: #PID<0.142.0>
Start Call: XPFinch.start_link([name: XPReq.Finch, pools: %{default: [protocols: [:http1]]}])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.274 [info] Child XPReq.FinchSupervisor of Supervisor #PID<0.141.0> (Supervisor.Default) started
Pid: #PID<0.147.0>
Start Call: DynamicSupervisor.start_link([strategy: :one_for_one, name: XPReq.FinchSupervisor])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.274 [info] Child XPReq.Test.Ownership of Supervisor #PID<0.141.0> (Supervisor.Default) started
Pid: #PID<0.148.0>
Start Call: XPReq.Test.Ownership.start_link([name: XPReq.Test.Ownership])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.274 [info] Application xp_req started at :nonode@nohost
23:22:21.274 [info] Application burrito started at :nonode@nohost
23:22:21.274 [info] Application xp_bunt started at :nonode@nohost
23:22:21.276 [info] Child ExUnit.Server of Supervisor ExUnit.Supervisor started
Pid: #PID<0.152.0>
Start Call: ExUnit.Server.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.276 [info] Child ExUnit.CaptureServer of Supervisor ExUnit.Supervisor started
Pid: #PID<0.153.0>
Start Call: ExUnit.CaptureServer.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.276 [info] Application ex_unit started at :nonode@nohost
23:22:21.276 [info] Application xp_file_system started at :nonode@nohost
23:22:21.280 [info] Child :httpc_manager of Supervisor :httpc_profile_sup started
Pid: #PID<0.159.0>
Start Call: :httpc_manager.start_link(:default, :only_session_cookies, :inets)
Restart: :permanent
Shutdown: 4000
Type: :worker
23:22:21.280 [info] Child :httpc_profile_sup of Supervisor :httpc_sup started
Pid: #PID<0.158.0>
Start Call: :httpc_profile_sup.start_link([httpc: {:default, :only_session_cookies}])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.280 [info] Child :httpc_handler_sup of Supervisor :httpc_sup started
Pid: #PID<0.160.0>
Start Call: :httpc_handler_sup.start_link()
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.280 [info] Child :httpc_sup of Supervisor :inets_sup started
Pid: #PID<0.157.0>
Start Call: :httpc_sup.start_link([httpc: {:default, :only_session_cookies}])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.281 [info] Child :httpd_sup of Supervisor :inets_sup started
Pid: #PID<0.161.0>
Start Call: :httpd_sup.start_link([])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.281 [info] Application inets started at :nonode@nohost
23:22:21.283 [info] Child XPCredo.CLI.Output.Shell of Supervisor XPCredo.Supervisor started
Pid: #PID<0.165.0>
Start Call: XPCredo.CLI.Output.Shell.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.283 [info] Child XPCredo.Service.SourceFileAST of Supervisor XPCredo.Supervisor started
Pid: #PID<0.166.0>
Start Call: XPCredo.Service.SourceFileAST.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.283 [info] Child XPCredo.Service.SourceFileLines of Supervisor XPCredo.Supervisor started
Pid: #PID<0.167.0>
Start Call: XPCredo.Service.SourceFileLines.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.283 [info] Child XPCredo.Service.SourceFileScopes of Supervisor XPCredo.Supervisor started
Pid: #PID<0.168.0>
Start Call: XPCredo.Service.SourceFileScopes.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.284 [info] Child XPCredo.Service.SourceFileScopePriorities of Supervisor XPCredo.Supervisor started
Pid: #PID<0.169.0>
Start Call: XPCredo.Service.SourceFileScopePriorities.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.284 [info] Child XPCredo.Service.SourceFileSource of Supervisor XPCredo.Supervisor started
Pid: #PID<0.170.0>
Start Call: XPCredo.Service.SourceFileSource.start_link([])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.284 [info] Application xp_credo started at :nonode@nohost
23:22:21.284 [info] Application erts started at :nonode@nohost
23:22:21.284 [info] Application xp_schematic started at :nonode@nohost
23:22:21.284 [info] Application xp_typed_struct started at :nonode@nohost
23:22:21.284 [info] Application xp_gen_lsp started at :nonode@nohost
23:22:21.287 [info] Child XPSnowflake.Generator of Supervisor #PID<0.173.0> (Supervisor.Default) started
Pid: #PID<0.174.0>
Start Call: XPSnowflake.Generator.start_link(0, 1023)
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.287 [info] Application xp_snowflake started at :nonode@nohost
23:22:21.287 [info] Application xp_sourceror started at :nonode@nohost
23:22:21.287 [info] Application xp_forge started at :nonode@nohost
23:22:21.287 [info] Application xp_logger_file_backend started at :nonode@nohost
23:22:21.288 [info] Child :ttb_autostart of Supervisor :runtime_tools_sup started
Pid: #PID<0.178.0>
Start Call: :ttb_autostart.start_link()
Restart: :temporary
Shutdown: 3000
Type: :worker
23:22:21.288 [info] Application runtime_tools started at :nonode@nohost
23:22:21.288 [info] Application et started at :nonode@nohost
23:22:21.288 [info] Application observer started at :nonode@nohost
23:22:21.288 [info] Application xp_path_glob started at :nonode@nohost
23:22:21.290 [info] Child XPForge.Document.Store of Supervisor XPExpert.Supervisor started
Pid: #PID<0.182.0>
Start Call: XPForge.Document.Store.start_link([derive: [analysis: &XPForge.Ast.analyze/1]])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.291 [info] Child XPExpert.ProjectSupervisor of Supervisor XPExpert.Supervisor started
Pid: #PID<0.183.0>
Start Call: DynamicSupervisor.start_link([name: XPExpert.ProjectSupervisor, strategy: :one_for_one])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.291 [info] Child XPExpert.DynamicSupervisor of Supervisor XPExpert.Supervisor started
Pid: #PID<0.184.0>
Start Call: DynamicSupervisor.start_link([name: XPExpert.DynamicSupervisor])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.291 [info] Child XPGenLSP.Assigns of Supervisor XPExpert.Supervisor started
Pid: #PID<0.185.0>
Start Call: XPGenLSP.Assigns.start_link([name: XPExpert.Assigns])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.291 [info] Child :expert_task_queue of Supervisor XPExpert.Supervisor started
Pid: #PID<0.186.0>
Start Call: Task.Supervisor.start_link([name: :expert_task_queue])
Restart: :permanent
Shutdown: :infinity
Type: :supervisor
23:22:21.292 [info] Child XPGenLSP.Buffer of Supervisor XPExpert.Supervisor started
Pid: #PID<0.187.0>
Start Call: XPGenLSP.Buffer.start_link([name: XPExpert.Buffer])
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.292 [debug] [name: XPExpert, buffer: XPExpert.Buffer, task_supervisor: :expert_task_queue, dynamic_supervisor: XPExpert.DynamicSupervisor, assigns: XPExpert.Assigns]
23:22:21.293 [info] Child XPExpert of Supervisor XPExpert.Supervisor started
Pid: #PID<0.188.0>
Start Call: XPExpert.start_link([name: XPExpert, buffer: XPExpert.Buffer, task_supervisor: :expert_task_queue, dynamic_supervisor: XPExpert.DynamicSupervisor, assigns: XPExpert.Assigns])
Restart: :permanent
Shutdown: 500
Type: :worker
23:22:21.293 [info] Application xp_expert started at :nonode@nohost
23:22:21.317 [info] Starting project at uri file:///home/daniel/Developer/my_project
23:22:21.322 [info] Child {XPExpert.Project.Progress, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.193.0>
Start Call: XPExpert.Project.Progress.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 2085})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.322 [info] Child {XPExpert.EngineSupervisor, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.194.0>
Start Call: XPExpert.EngineSupervisor.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 2085})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:21.324 [debug] sent request server -> client window/workDoneProgress/create
23:22:21.327 [info] Child :erl_epmd of Supervisor :net_sup started
Pid: #PID<0.197.0>
Start Call: :erl_epmd.start_link()
Restart: :permanent
Shutdown: 2000
Type: :worker
23:22:21.328 [info] Child :auth of Supervisor :net_sup started
Pid: #PID<0.198.0>
Start Call: :auth.start_link()
Restart: :permanent
Shutdown: 2000
Type: :worker
23:22:21.329 [error] ** (XPGenLSP.InvalidRequest) Invalid request from the client
Received: %{"error" => %{"code" => -32601, "message" => "Unhandled method window/workDoneProgress/create"}, "id" => 1, "jsonrpc" => "2.0"}
Errors: "unexpected request payload"
23:22:21.330 [info] Child :net_kernel of Supervisor :net_sup started
Pid: #PID<0.199.0>
Start Call: :net_kernel.start_link(%{name: :"[email protected]", supervisor: :net_sup_dynamic, name_domain: :longnames, clean_halt: false})
Restart: :permanent
Shutdown: 2000
Type: :worker
23:22:21.330 [debug] Child :net_sup_dynamic of Supervisor :kernel_sup started
Pid: #PID<0.196.0>
Start Call: :erl_distribution.start_link(%{name: :"[email protected]", supervisor: :net_sup_dynamic, name_domain: :longnames, clean_halt: false})
Restart: :permanent
Shutdown: 1000
Type: :supervisor
23:22:21.353 [debug] handled request client -> server initialize in 55ms
23:22:21.356 [debug] sent notification server -> client window/logMessage
23:22:21.916 [info] wait_for_engine -> data: {:data, ~c"* creating /home/daniel/.mix/archives/hex-2.2.2-otp-27\n"}
23:22:22.120 [info] wait_for_engine -> data: {:data, ~c"* creating /home/daniel/.mix/elixir/1-18-otp-28/rebar3\n"}
23:22:22.185 [info] wait_for_engine -> data: {:data, ~c"* Getting elixir_sense (https://github.com/elixir-lsp/elixir_sense.git - e3ddc403554050221a2fd19a10a896fa7525bc02)\n"}
23:22:22.847 [info] wait_for_engine -> data: {:data, ~c"remote: Enumerating objects: 17007, done. \nremote: Counting objects: 0% (1/2573) \rremote: Counting objects: 1% (26/2573) \rremote: Counting objects: 2% (52/2573) \rremote: Counting objects: 3% (78/2573) \rremote: Counting objects: 4% (103/2573) \rremote: Counting objects: 5% (129/2573) \rremote: Counting objects: 6% (155/2573) \rremote: Counting objects: 7% (181/2573) \rremote: Counting objects: 8% (206/2573) \rremote: Counting objects: 9% (232/2573) \rremote: Counting objects: 10% (258/2573) \rremote: Counting objects: 11% (284/2573) \rremote: Counting objects: 12% (309/2573) \rremote: Counting objects: 13% (335/2573) \rremote: Counting objects: 14% (361/2573) \rremote: Counting objects: 15% (386/2573) \rremote: Counting objects: 16% (412/2573) \rremote: Counting objects: 17% (438/2573) \rremote: Counting objects: 18% (464/2573) \rremote: Counting objects: 19% (489/2573) \rremote: Counting objects: 20% (515/2573) \rremote: Counting objects: 21% (541/2573) \rremote: Counting objects: 22% (567/2573) \rremote: Counting objects: 23% (592/2573) \rremote: Counting objects: 24% (618/2573) \rremote: Counting objects: 25% (644/2573) \rremote: Counting objects: 26% (669/2573) \rremote: Counting objects: 27% (695/2573) \rremote: Counting objects: 28% (721/2573) \rremote: Counting objects: 29% (747/2573) \rremote: Counting objects: 30% (772/2573) \rremote: Counting objects: 31% (798/2573) \rremote: Counting objects: 32% (824/2573) \rremote: Counting objects: 33% (850/2573) \rremote: Counting objects: 34% (875/2573) \rremote: Counting objects: 35% (901/2573) \rremote: Counting objects: 36% (927/2573) \rremote: Counting objects: 37% (953/2573) \rremote: Counting objects: 38% (978/2573) \rremote: Counting objects: 39% (1004/2573) \rremote: Counting objects: 40% (1030/2573) \rremote: Counting objects: 41% (1055/2573) \rremote: Counting objects: 42% (1081/2573) \rremote: Counting objects: 43% (1107/2573) \rremote: Counting objects: 44% (1133/2573) \rremote: Counting objects: 45% (1158/2573) \rremote: Counting objects: 46% (1184/2573) \rremote: Counting objects: 47% (1210/2573) \rremote: Counting objects: 48% (1236/2573) \rremote: Counting objects: 49% (1261/2573) \rremote: Counting objects: 50% (1287/2573) \rremote: Counting objects: 51% (1313/2573) \rremote: Counting objects: 52% (1338/2573) \rremote: Counting objects: 53% (1364/2573) \rremote: Counting objects: 54% (1390/2573) \rremote: Counting objects: 55% (1416/2573) \rremote: Counting objects: 56% (1441/2573) \rremote: Counting objects: 57% (1467/2573) \rremote: Counting objects: 58% (1493/2573) \rremote: Counting objects: 59% (1519/2573) \rremote: Counting objects: 60% (1544/2573) \rremote: Counting objects: 61% (1570/2573) \rremote: Counting objects: 62% (1596/2573) \rremote: Counting objects: 63% (1621/2573) \rremote: Counting objects: 64% (1647/2573) \rremote: Counting objects: 65% (1673/2573) \rremote: Counting objects: 66% (1699/2573) \rremote: Counting objects: 67% (1724/2573) \rremote: Counting objects: 68% (1750/2573) \rremote: Counting objects: 69% (1776/2573) \rremote: Counting objects: 70% (1802/2573) \rremote: Counting objects: 71% (1827/2573) \rremote: Counting objects: 72% (1853/2573) \rremote: Counting objects: 73% (1879/2573) \rremote: Counting objects: 74% (1905/2573) \rremote: Counting objects: 75% (1930/2573) \rremote: Counting objects: 76% (1956/2573) \rremote: Counting objects: 77% (1982/2573) \rremote: Counting objects: 78% (2007/2573) \rremote: Counting objects: 79% (2033/2573) \rremote: Co" ++ ...}
23:22:22.939 [info] wait_for_engine -> data: {:data, ~c"remote: Compressing objects: 1% (6/512) \rremote: Compressing objects: 2% (11/512) \rremote: Compressing objects: 3% (16/512) \rremote: Compressing objects: 4% (21/512) \rremote: Compressing objects: 5% (26/512) \rremote: Compressing objects: 6% (31/512) \rremote: Compressing objects: 7% (36/512) \rremote: Compressing objects: 8% (41/512) \rremote: Compressing objects: 9% (47/512) \rremote: Compressing objects: 10% (52/512) \rremote: Compressing objects: 11% (57/512) \rremote: Compressing objects: 12% (62/512) \rremote: Compressing objects: 13% (67/512) \r"}
23:22:22.941 [info] wait_for_engine -> data: {:data, ~c"remote: Compressing objects: 14% (72/512) \r"}
23:22:22.961 [info] wait_for_engine -> data: {:data, ~c"remote: Compressing objects: 15% (77/512) \rremote: Compressing objects: 16% (82/512) \rremote: Compressing objects: 17% (88/512) \rremote: Compressing objects: 18% (93/512) \rremote: Compressing objects: 19% (98/512) \rremote: Compressing objects: 20% (103/512) \rremote: Compressing objects: 21% (108/512) \rremote: Compressing objects: 22% (113/512) \rremote: Compressing objects: 23% (118/512) \rremote: Compressing objects: 24% (123/512) \rremote: Compressing objects: 25% (128/512) \rremote: Compressing objects: 26% (134/512) \rremote: Compressing objects: 27% (139/512) \rremote: Compressing objects: 28% (144/512) \rremote: Compressing objects: 29% (149/512) \rremote: Compressing objects: 30% (154/512) \rremote: Compressing objects: 31% (159/512) \rremote: Compressing objects: 32% (164/512) \rremote: Compressing objects: 33% (169/512) \rremote: Compressing objects: 34% (175/512) \rremote: Compressing objects: 35% (180/512) \r"}
23:22:22.962 [info] wait_for_engine -> data: {:data, ~c"remote: Compressing objects: 36% (185/512) \rremote: Compressing objects: 37% (190/512) \rremote: Compressing objects: 38% (195/512) \rremote: Compressing objects: 39% (200/512) \rremote: Compressing objects: 40% (205/512) \rremote: Compressing objects: 41% (210/512) \rremote: Compressing objects: 42% (216/512) \rremote: Compressing objects: 43% (221/512) \rremote: Compressing objects: 44% (226/512) \rremote: Compressing objects: 45% (231/512) \rremote: Compressing objects: 46% (236/512) \rremote: Compressing objects: 47% (241/512) \rremote: Compressing objects: 48% (246/512) \rremote: Compressing objects: 49% (251/512) \rremote: Compressing objects: 50% (256/512) \rremote: Compressing objects: 51% (262/512) \rremote: Compressing objects: 52% (267/512) \rremote: Compressing objects: 53% (272/512) \rremote: Compressing objects: 54% (277/512) \rremote: Compressing objects: 55% (282/512) \rremote: Compressing objects: 56% (287/512) \r"}
23:22:22.966 [info] wait_for_engine -> data: {:data, ~c"remote: Compressing objects: 57% (292/512) \rremote: Compressing objects: 58% (297/512) \rremote: Compressing objects: 59% (303/512) \rremote: Compressing objects: 60% (308/512) \rremote: Compressing objects: 61% (313/512) \rremote: Compressing objects: 62% (318/512) \rremote: Compressing objects: 63% (323/512) \rremote: Compressing objects: 64% (328/512) \rremote: Compressing objects: 65% (333/512) \rremote: Compressing objects: 66% (338/512) \rremote: Compressing objects: 67% (344/512) \rremote: Compressing objects: 68% (349/512) \rremote: Compressing objects: 69% (354/512) \rremote: Compressing objects: 70% (359/512) \rremote: Compressing objects: 71% (364/512) \rremote: Compressing objects: 72% (369/512) \rremote: Compressing objects: 73% (374/512) \rremote: Compressing objects: 74% (379/512) \rremote: Compressing objects: 75% (384/512) \rremote: Compressing objects: 76% (390/512) \rremote: Compressing objects: 77% (395/512) \r"}
23:22:22.966 [info] wait_for_engine -> data: {:data, ~c"remote: Compressing objects: 78% (400/512) \rremote: Compressing objects: 79% (405/512) \rremote: Compressing objects: 80% (410/512) \rremote: Compressing objects: 81% (415/512) \rremote: Compressing objects: 82% (420/512) \rremote: Compressing objects: 83% (425/512) \rremote: Compressing objects: 84% (431/512) \rremote: Compressing objects: 85% (436/512) \rremote: Compressing objects: 86% (441/512) \rremote: Compressing objects: 87% (446/512) \rremote: Compressing objects: 88% (451/512) \rremote: Compressing objects: 89% (456/512) \rremote: Compressing objects: 90% (461/512) \rremote: Compressing objects: 91% (466/512) \rremote: Compressing objects: 92% (472/512) \rremote: Compressing objects: 93% (477/512) \rremote: Compressing objects: 94% (482/512) \rremote: Compressing objects: 95% (487/512) \rremote: Compressing objects: 96% (492/512) \rremote: Compressing objects: 97% (497/512) \rremote: Compressing objects: 98% (502/512) \rremote: Compressing objects: 99% (507/512) \rremote: Compressing objects: 100% (512/512) \rremote: Compressing objects: 100% (512/512), done. \n"}
23:22:23.540 [info] wait_for_engine -> data: {:data, ~c"remote: Total 17007 (delta 2182), reused 2100 (delta 2058), pack-reused 14434 (from 4) \n"}
23:22:23.818 [info] wait_for_engine -> data: {:data, ~c"Resolving Hex dependencies...\n"}
23:22:23.834 [info] wait_for_engine -> data: {:data, ~c"Resolution completed in 0.015s\n"}
23:22:23.835 [info] wait_for_engine -> data: {:data, ~c"Unchanged:\n"}
23:22:23.835 [info] wait_for_engine -> data: {:data, ~c" bunt 1.0.0\n credo 1.7.12\n file_system 1.1.0\n gen_lsp 0.11.0\n jason 1.4.4\n nimble_options 1.1.1\n"}
23:22:23.835 [info] wait_for_engine -> data: {:data, ~c" nimble_parsec 1.2.3\n patch 0.15.0\n path_glob 0.2.0\n refactorex 0.1.52\n schematic 0.2.1\n snowflake 1.0.4\n"}
23:22:23.835 [info] wait_for_engine -> data: {:data, ~c" sourceror 1.10.0\n telemetry 1.3.0\n typed_struct 0.3.0\n"}
23:22:23.837 [info] wait_for_engine -> data: {:data, ~c"* Getting credo (Hex package)\n"}
23:22:23.872 [info] wait_for_engine -> data: {:data, ~c"* Getting gen_lsp (Hex package)\n"}
23:22:23.916 [info] wait_for_engine -> data: {:data, ~c"* Getting patch (Hex package)\n"}
23:22:23.923 [info] wait_for_engine -> data: {:data, ~c"* Getting path_glob (Hex package)\n"}
23:22:23.925 [info] wait_for_engine -> data: {:data, ~c"* Getting sourceror (Hex package)\n"}
23:22:23.928 [info] wait_for_engine -> data: {:data, ~c"* Getting refactorex (Hex package)\n"}
23:22:23.936 [info] wait_for_engine -> data: {:data, ~c"* Getting nimble_parsec (Hex package)\n"}
23:22:23.938 [info] wait_for_engine -> data: {:data, ~c"* Getting jason (Hex package)\n"}
23:22:23.940 [info] wait_for_engine -> data: {:data, ~c"* Getting nimble_options (Hex package)\n"}
23:22:23.942 [info] wait_for_engine -> data: {:data, ~c"* Getting schematic (Hex package)\n"}
23:22:23.943 [info] wait_for_engine -> data: {:data, ~c"* Getting typed_struct (Hex package)\n"}
23:22:23.945 [info] wait_for_engine -> data: {:data, ~c"* Getting telemetry (Hex package)\n"}
23:22:23.947 [info] wait_for_engine -> data: {:data, ~c"* Getting snowflake (Hex package)\n"}
23:22:23.949 [info] wait_for_engine -> data: {:data, ~c"* Getting bunt (Hex package)\n"}
23:22:23.950 [info] wait_for_engine -> data: {:data, ~c"* Getting file_system (Hex package)\n"}
23:22:23.953 [info] wait_for_engine -> data: {:data, ~c"You have added/upgraded packages you could sponsor, run `mix hex.sponsor` to learn more\n"}
23:22:24.106 [info] wait_for_engine -> data: {:data, ~c"==> typed_struct\nCompiling 2 files (.ex)\n"}
23:22:24.144 [info] wait_for_engine -> data: {:data, ~c"Generated typed_struct app\n"}
23:22:24.159 [info] wait_for_engine -> data: {:data, ~c"==> file_system\nCompiling 7 files (.ex)\n"}
23:22:24.223 [info] wait_for_engine -> data: {:data, ~c"Generated file_system app\n"}
23:22:24.238 [info] wait_for_engine -> data: {:data, ~c"==> nimble_options\nCompiling 3 files (.ex)\n"}
23:22:24.412 [info] wait_for_engine -> data: {:data, ~c"Generated nimble_options app\n"}
23:22:24.422 [info] wait_for_engine -> data: {:data, ~c"==> snowflake\nCompiling 4 files (.ex)\n"}
23:22:24.428 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 117, 115, 101, 32, 66, 105, 116, 119, 105, 115, 101, 32, 105, 115, 32, 100, 101, 112, 114, 101, 99, 97, 116, 101, 100, 46, 32, 105, 109, 112, 111, 114, 116, 32, 66, ...]}
23:22:24.442 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 83, 117, 112, 101, 114, 118, 105, 115, 111, 114, 46, 83, 112, 101, 99, 46, 119, 111, 114, 107, 101, 114, 47, 50, 32, 105, 115, 32, 100, 101, 112, 114, 101, 99, 97, ...]}
23:22:24.447 [info] wait_for_engine -> data: {:data, ~c"Generated snowflake app\n"}
23:22:24.459 [info] wait_for_engine -> data: {:data, ~c"==> bunt\nCompiling 2 files (.ex)\n"}
23:22:24.662 [info] wait_for_engine -> data: {:data, ~c"Generated bunt app\n"}
23:22:24.673 [info] wait_for_engine -> data: {:data, ~c"==> jason\n"}
23:22:24.673 [info] wait_for_engine -> data: {:data, ~c"Compiling 10 files (.ex)\n"}
23:22:25.333 [info] wait_for_engine -> data: {:data, ~c"Generated jason app\n"}
23:22:25.335 [info] wait_for_engine -> data: {:data, ~c"==> elixir_sense\nCompiling 1 file (.yrl)\n"}
23:22:26.456 [info] wait_for_engine -> data: {:data, ~c"Compiling 5 files (.erl)\n"}
23:22:28.762 [info] wait_for_engine -> data: {:data, ~c"Compiling 88 files (.ex)\n"}
23:22:29.745 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 58, 101, 108, 105, 120, 105, 114, 95, 100, 101, 102, 46, 108, 111, 99, 97, 108, 95, 102, 111, 114, 47, 52, 32, 105, 115, 32, 117, 110, 100, 101, 102, 105, 110, 101, ...]}
23:22:29.752 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 58, 101, 108, 105, 120, 105, 114, 95, 114, 101, 119, 114, 105, 116, 101, 46, 109, 97, 116, 99, 104, 95, 114, 101, 119, 114, 105, 116, 101, 47, 53, 32, 105, 115, 32, ...]}
23:22:29.753 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 58, 101, 108, 105, 120, 105, 114, 95, 114, 101, 119, 114, 105, 116, 101, 46, 103, 117, 97, 114, 100, 95, 114, 101, 119, 114, 105, 116, 101, 47, 54, 32, 105, 115, 32, ...]}
23:22:29.765 [info] wait_for_engine -> data: {:data, ~c"Generated elixir_sense app\n"}
23:22:29.802 [info] wait_for_engine -> data: {:data, ~c"==> patch\nCompiling 43 files (.ex)\n"}
23:22:30.142 [info] wait_for_engine -> data: {:data, ~c"Generated patch app\n"}
23:22:30.183 [info] wait_for_engine -> data: {:data, ~c"==> sourceror\nCompiling 12 files (.ex)\n"}
23:22:30.440 [info] wait_for_engine -> data: {:data, ~c"Generated sourceror app\n"}
23:22:30.456 [info] wait_for_engine -> data: {:data, ~c"==> refactorex\nCompiling 47 files (.ex)\n"}
23:22:30.680 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 116, 104, 101, 32, 102, 111, 108, 108, 111, 119, 105, 110, 103, 32, 99, 108, 97, 117, 115, 101, 32, 119, 105, 108, 108, 32, 110, 101, 118, 101, 114, 32, 109, 97, 116, ...]}
23:22:30.680 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 116, 104, 101, 32, 102, 111, 108, 108, 111, 119, 105, 110, 103, 32, 99, 108, 97, 117, 115, 101, 32, 119, 105, 108, 108, 32, 110, 101, 118, 101, 114, 32, 109, 97, 116, ...]}
23:22:30.680 [info] wait_for_engine -> data: {:data, [32, 32, 32, 32, 119, 97, 114, 110, 105, 110, 103, 58, 32, 116, 104, 101, 32, 102, 111, 108, 108, 111, 119, 105, 110, 103, 32, 99, 108, 97, 117, 115, 101, 32, 119, 105, 108, 108, 32, 110, 101, 118, 101, 114, 32, 109, 97, 116, ...]}
23:22:30.686 [info] wait_for_engine -> data: {:data, ~c"Generated refactorex app\n"}
23:22:30.710 [info] wait_for_engine -> data: {:data, ~c"==> nimble_parsec\nCompiling 4 files (.ex)\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: _.._ inside match is deprecated, you must always match on the step: _.._//var or _.._//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:925: NimbleParsec.Compiler.newline_allowed?/1\n\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: _.._ inside match is deprecated, you must always match on the step: _.._//var or _.._//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:934: NimbleParsec.Compiler.newline_forbidden?/1\n\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: min..max inside match is deprecated, you must always match on the step: min..max//var or min..max//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1041: NimbleParsec.Compiler.bin_range_to_guard/2\n\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: min..max inside match is deprecated, you must always match on the step: min..max//var or min..max//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1044: NimbleParsec.Compiler.bin_range_to_guard/2\n\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: min..min inside match is deprecated, you must always match on the step: min..min//var or min..min//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1047: NimbleParsec.Compiler.bin_range_to_guard/2\n\nwarning: min..max inside match is deprecated, you must always match on the step: min..max//var or min..max//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1053: NimbleParsec.Compiler.bin_range_to_guard/2\n\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: min..max inside match is deprecated, you must always match on the step: min..max//var or min..max//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1056: NimbleParsec.Compiler.bin_range_to_guard/2\n\nwarning: min..min inside match is deprecated, you must always match on the step: min..min//var or min..min//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1059: NimbleParsec.Compiler.bin_range_to_guard/2\n\n"}
23:22:30.735 [info] wait_for_engine -> data: {:data, ~c"warning: min..max inside match is deprecated, you must always match on the step: min..max//var or min..max//_ if you want to ignore it\n lib/nimble_parsec/compiler.ex:1067: NimbleParsec.Compiler.inspect_bin_range/2\n\n"}
23:22:30.744 [info] wait_for_engine -> data: {:data, ~c"warning: min..max inside match is deprecated, you must always match on the step: min..max//var or min..max//_ if you want to ignore it\n lib/nimble_parsec.ex:423: NimbleParsec.exclude_bin_segment?/2\n\n"}
23:22:30.744 [info] wait_for_engine -> data: {:data, ~c"warning: _.._ inside match is deprecated, you must always match on the step: _.._//var or _.._//_ if you want to ignore it\n lib/nimble_parsec.ex:427: NimbleParsec.int_random/1\n\n"}
23:22:30.752 [info] wait_for_engine -> data: {:data, ~c"warning: _.._ inside match is deprecated, you must always match on the step: _.._//var or _.._//_ if you want to ignore it\n lib/nimble_parsec.ex:1796: NimbleParsec.split_range!/2\n\n"}
23:22:30.752 [info] wait_for_engine -> data: {:data, ~c"warning: _.._ inside match is deprecated, you must always match on the step: _.._//var or _.._//_ if you want to ignore it\n lib/nimble_parsec.ex:1798: NimbleParsec.split_range!/2\n\n"}
23:22:30.868 [info] wait_for_engine -> data: {:data, ~c"Generated nimble_parsec app\n"}
23:22:31.056 [info] wait_for_engine -> data: {:data, ~c"==> mix_install\n===> Analyzing applications...\n"}
23:22:31.075 [info] wait_for_engine -> data: {:data, ~c"===> Compiling telemetry\n"}
23:22:31.223 [info] wait_for_engine -> data: {:data, ~c"==> schematic\n"}
23:22:31.223 [info] wait_for_engine -> data: {:data, ~c"Compiling 2 files (.ex)\n"}
23:22:31.314 [info] wait_for_engine -> data: {:data, ~c"Generated schematic app\n"}
23:22:31.331 [info] wait_for_engine -> data: {:data, ~c"==> credo\nCompiling 251 files (.ex)\n"}
23:22:33.569 [info] wait_for_engine -> data: {:data, ~c"Generated credo app\n"}
23:22:33.630 [info] wait_for_engine -> data: {:data, ~c"==> gen_lsp\nCompiling 472 files (.ex)\n"}
23:22:39.702 [info] wait_for_engine -> data: {:data, ~c"Generated gen_lsp app\n"}
23:22:40.479 [info] wait_for_engine -> data: {:data, ~c"==> forge\n"}
23:22:40.480 [info] wait_for_engine -> data: {:data, ~c"Compiling 5 files (.erl)\n"}
23:22:42.915 [info] wait_for_engine -> data: {:data, ~c"Compiling 101 files (.ex)\n"}
23:22:43.935 [info] wait_for_engine -> data: {:data, ~c"Generated forge app\n"}
23:22:43.974 [info] wait_for_engine -> data: {:data, ~c"==> path_glob\n"}
23:22:43.975 [info] wait_for_engine -> data: {:data, ~c"Compiling 2 files (.ex)\n"}
23:22:46.650 [info] wait_for_engine -> data: {:data, ~c"Generated path_glob app\n"}
23:22:46.656 [info] wait_for_engine -> data: {:data, ~c"==> engine\nCompiling 96 files (.ex)\n"}
23:22:47.818 [info] wait_for_engine -> data: {:data, ~c"Generated engine app\n"}
23:22:48.340 [info] wait_for_engine -> data: {:data, ~c"Rewriting 17 app files\n"}
23:22:48.370 [info] wait_for_engine -> data: {:data, ~c"Rewriting .beam files\n"}
23:22:48.404 [info] wait_for_engine -> data: {:data, ~c" Found 0 protocols\n"}
23:22:48.404 [info] wait_for_engine -> data: {:data, ~c" Found 1593 app beam files\n"}
23:22:48.408 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.409 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 0% complete"}
23:22:48.410 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.410 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 0% complete"}
23:22:48.411 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 0% complete"}
23:22:48.412 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.414 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 0% complete"}
23:22:48.414 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.415 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 0% complete\r"}
23:22:48.415 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 0% complete"}
23:22:48.416 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 0% complete\r"}
23:22:48.421 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 1% complete"}
23:22:48.422 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.423 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 1% complete"}
23:22:48.424 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.424 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 1% complete"}
23:22:48.424 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 1% complete"}
23:22:48.425 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.425 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 1% complete"}
23:22:48.425 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 1% complete"}
23:22:48.425 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 1% complete"}
23:22:48.426 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.427 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 1% complete"}
23:22:48.429 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 1% complete\r Applying namespace: 2% complete\r Applying namespace: 2% complete\r Applying namespace: 2% complete\r Applying namespace: 2% complete\r Applying namespace: 2% complete"}
23:22:48.430 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.430 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete"}
23:22:48.431 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.431 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete\r Applying namespace: 2% complete"}
23:22:48.431 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.431 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete\r"}
23:22:48.431 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete"}
23:22:48.432 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.432 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete"}
23:22:48.432 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.433 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete"}
23:22:48.434 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.434 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete"}
23:22:48.439 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.440 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete"}
23:22:48.440 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.441 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 2% complete\r Applying namespace: 2% complete"}
23:22:48.442 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.443 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.444 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 3% complete\r"}
23:22:48.448 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete\r"}
23:22:48.448 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.450 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.450 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.450 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 3% complete"}
23:22:48.454 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.454 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete\r Applying namespace: 3% complete\r"}
23:22:48.455 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.456 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.457 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete\r"}
23:22:48.458 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.458 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.460 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.462 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.463 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete\r"}
23:22:48.465 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete\r Applying namespace: 3% complete\r"}
23:22:48.466 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 3% complete"}
23:22:48.467 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.470 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete"}
23:22:48.470 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 4% complete\r Applying namespace: 4% complete"}
23:22:48.470 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 4% complete"}
23:22:48.471 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.471 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete"}
23:22:48.471 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.474 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete"}
23:22:48.474 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.474 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete"}
23:22:48.475 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.480 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete"}
23:22:48.486 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 4% complete\r Applying namespace: 4% complete\r"}
23:22:48.493 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete\r Applying namespace: 4% complete\r Applying namespace: 4% complete\r"}
23:22:48.496 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete\r"}
23:22:48.497 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete"}
23:22:48.497 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.498 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 4% complete\r Applying namespace: 5% complete\r Applying namespace: 5% complete\r"}
23:22:48.500 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.508 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.509 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.510 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.510 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.510 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.510 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.511 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.513 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.514 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.518 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.522 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.523 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.524 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.525 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.525 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.526 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.527 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.527 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.528 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.528 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.528 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.529 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.531 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.531 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete"}
23:22:48.538 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.538 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 5% complete\r"}
23:22:48.543 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete\r"}
23:22:48.543 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete"}
23:22:48.545 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.546 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete\r"}
23:22:48.550 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete\r Applying namespace: 6% complete\r"}
23:22:48.552 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete"}
23:22:48.562 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 6% complete"}
23:22:48.565 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.567 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete"}
23:22:48.570 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 6% complete\r Applying namespace: 6% complete"}
23:22:48.576 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 6% complete"}
23:22:48.588 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.590 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete"}
23:22:48.599 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 6% complete"}
23:22:48.599 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.604 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 6% complete\r Applying namespace: 6% complete"}
23:22:48.605 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 6% complete"}
23:22:48.606 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 7% complete\r Applying namespace: 7% complete"}
23:22:48.618 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.619 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete"}
23:22:48.621 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.623 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete\r Applying namespace: 7% complete\r Applying namespace: 7% complete"}
23:22:48.624 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.625 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete"}
23:22:48.636 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.638 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete\r Applying namespace: 7% complete"}
23:22:48.647 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.651 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete\r Applying namespace: 7% complete"}
23:22:48.653 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.654 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete"}
23:22:48.664 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.665 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete"}
23:22:48.666 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.668 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete"}
23:22:48.668 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.668 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 7% complete"}
23:22:48.670 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 7% complete"}
23:22:48.672 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 8% complete\r Applying namespace: 8% complete\r Applying namespace: 8% complete"}
23:22:48.674 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 8% complete"}
23:22:48.677 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.680 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.680 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.681 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.682 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.684 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.693 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.693 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.698 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 8% complete\r"}
23:22:48.700 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.704 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 8% complete"}
23:22:48.704 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 8% complete"}
23:22:48.714 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.717 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete\r"}
23:22:48.718 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.718 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.718 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.718 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.718 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 8% complete"}
23:22:48.718 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.719 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.726 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.728 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.730 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.730 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.731 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.731 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.735 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.739 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.744 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.745 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.745 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 9% complete\r Applying namespace: 9% complete"}
23:22:48.753 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.754 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.758 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 9% complete"}
23:22:48.763 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.764 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.767 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.768 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.770 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 9% complete"}
23:22:48.773 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.776 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.778 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 9% complete"}
23:22:48.780 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.780 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 9% complete"}
23:22:48.781 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 10% complete"}
23:22:48.783 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 10% complete"}
23:22:48.786 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.786 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete"}
23:22:48.787 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 10% complete"}
23:22:48.799 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.801 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete\r Applying namespace: 10% complete\r Applying namespace: 10% complete\r"}
23:22:48.802 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete"}
23:22:48.803 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.804 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete"}
23:22:48.807 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.809 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete"}
23:22:48.812 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.814 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete"}
23:22:48.817 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.819 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete\r Applying namespace: 10% complete"}
23:22:48.824 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.825 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 10% complete\r Applying namespace: 10% complete"}
23:22:48.832 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 10% complete"}
23:22:48.840 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.840 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.852 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.853 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.854 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 11% complete"}
23:22:48.855 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.856 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete\r"}
23:22:48.856 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.861 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.865 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.867 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.867 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.873 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.874 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.881 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.881 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete\r Applying namespace: 11% complete"}
23:22:48.887 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 11% complete"}
23:22:48.887 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.889 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.889 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 11% complete"}
23:22:48.889 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.889 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 11% complete"}
23:22:48.889 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 11% complete"}
23:22:48.893 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 11% complete"}
23:22:48.893 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 12% complete"}
23:22:48.899 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 12% complete\r Applying namespace: 12% complete\r"}
23:22:48.900 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete"}
23:22:48.902 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 12% complete"}
23:22:48.903 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.904 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete"}
23:22:48.905 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.905 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete\r"}
23:22:48.906 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete"}
23:22:48.914 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 12% complete"}
23:22:48.921 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.923 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete\r"}
23:22:48.925 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete\r Applying namespace: 12% complete"}
23:22:48.928 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.929 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete"}
23:22:48.930 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.933 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 12% complete\r Applying namespace: 12% complete\r Applying namespace: 12% complete\r Applying namespace: 13% complete\r Applying namespace: 13% complete\r Applying namespace: 13% complete"}
23:22:48.939 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.942 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 13% complete\r Applying namespace: 13% complete\r"}
23:22:48.943 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 13% complete"}
23:22:48.950 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 13% complete\r Applying namespace: 13% complete\r Applying namespace: 13% complete\r Applying namespace: 13% complete\r Applying namespace: 13% complete\r Applying namespace: 13% complete"}
23:22:48.957 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.958 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 13% complete\r Applying namespace: 13% complete"}
23:22:48.959 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.959 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 13% complete"}
23:22:48.959 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.959 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 13% complete"}
23:22:48.960 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.961 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.964 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.965 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.966 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.966 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.967 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.967 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.971 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.972 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete\r Applying namespace: 14% complete"}
23:22:48.973 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.974 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.975 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.977 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.981 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 14% complete\r Applying namespace: 14% complete\r Applying namespace: 14% complete"}
23:22:48.983 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.984 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.984 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.984 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.989 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.990 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete"}
23:22:48.993 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.998 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 14% complete\r"}
23:22:48.998 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete\r"}
23:22:48.998 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete"}
23:22:48.998 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 15% complete\r"}
23:22:48.999 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete"}
23:22:48.999 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:48.999 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete"}
23:22:49.000 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 15% complete"}
23:22:49.000 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.000 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete"}
23:22:49.001 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 15% complete\r Applying namespace: 15% complete"}
23:22:49.006 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.009 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete"}
23:22:49.012 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.014 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 15% complete"}
23:22:49.019 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 15% complete\r Applying namespace: 15% complete\r Applying namespace: 15% complete\r Applying namespace: 15% complete\r Applying namespace: 15% complete"}
23:22:49.019 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.019 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete"}
23:22:49.019 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 16% complete"}
23:22:49.020 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.020 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete"}
23:22:49.022 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.022 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete\r Applying namespace: 16% complete"}
23:22:49.024 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.024 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete"}
23:22:49.026 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.032 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete"}
23:22:49.034 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 16% complete\r Applying namespace: 16% complete\r Applying namespace: 16% complete"}
23:22:49.038 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.039 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete"}
23:22:49.043 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.046 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 16% complete"}
23:22:49.046 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 16% complete"}
23:22:49.051 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 16% complete\r Applying namespace: 16% complete\r Applying namespace: 16% complete"}
23:22:49.056 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.056 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.072 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.074 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.075 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 17% complete"}
23:22:49.088 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.089 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete\r"}
23:22:49.089 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.092 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.093 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.101 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.103 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.104 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.105 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.105 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.105 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.109 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.110 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.114 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.117 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete\r Applying namespace: 17% complete\r"}
23:22:49.119 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete"}
23:22:49.135 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.139 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 17% complete\r Applying namespace: 17% complete"}
23:22:49.171 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 17% complete\r"}
23:22:49.172 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete\r"}
23:22:49.172 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.177 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.180 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.188 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.189 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.190 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.190 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.192 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.193 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.193 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.195 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.201 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.202 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.204 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.206 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete\r Applying namespace: 18% complete"}
23:22:49.212 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.214 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete\r Applying namespace: 18% complete"}
23:22:49.222 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 18% complete\r"}
23:22:49.222 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.224 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.224 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 18% complete"}
23:22:49.234 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 18% complete\r Applying namespace: 19% complete"}
23:22:49.235 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.236 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.240 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.248 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete\r Applying namespace: 19% complete"}
23:22:49.252 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.254 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.278 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.280 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete\r Applying namespace: 19% complete"}
23:22:49.282 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.282 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.286 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.288 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.299 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.301 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.304 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.306 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.320 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.324 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete\r Applying namespace: 19% complete"}
23:22:49.335 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.339 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete"}
23:22:49.353 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 19% complete"}
23:22:49.356 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.357 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 19% complete\r Applying namespace: 20% complete"}
23:22:49.358 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 20% complete"}
23:22:49.359 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.359 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete"}
23:22:49.363 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 20% complete\r Applying namespace: 20% complete"}
23:22:49.364 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 20% complete"}
23:22:49.371 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.371 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete"}
23:22:49.381 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.382 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete"}
23:22:49.384 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 20% complete\r"}
23:22:49.384 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete"}
23:22:49.390 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.390 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete\r Applying namespace: 20% complete"}
23:22:49.390 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.392 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete"}
23:22:49.393 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.393 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 20% complete"}
23:22:49.395 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 20% complete"}
23:22:49.396 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 20% complete"}
23:22:49.405 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.407 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.413 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.413 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete\r Applying namespace: 21% complete"}
23:22:49.423 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.425 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.426 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.427 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.432 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.433 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.435 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.435 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.436 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.438 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete\r Applying namespace: 21% complete"}
23:22:49.451 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 21% complete\r Applying namespace: 21% complete"}
23:22:49.455 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.455 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.458 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.459 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.467 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.468 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.472 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.473 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.483 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.485 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 21% complete"}
23:22:49.485 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.490 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete\r"}
23:22:49.491 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete"}
23:22:49.495 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 22% complete"}
23:22:49.498 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.498 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete"}
23:22:49.500 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.500 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete"}
23:22:49.501 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.502 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete"}
23:22:49.507 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 22% complete"}
23:22:49.507 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 22% complete"}
23:22:49.508 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.508 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete\r"}
23:22:49.508 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete"}
23:22:49.509 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.511 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete\r Applying namespace: 22% complete"}
23:22:49.512 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.512 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete"}
23:22:49.520 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 22% complete"}
23:22:49.525 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.526 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 22% complete\r Applying namespace: 22% complete"}
23:22:49.528 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.530 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.532 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 23% complete\r Applying namespace: 23% complete\r Applying namespace: 23% complete"}
23:22:49.536 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.536 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.540 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.541 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.546 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 23% complete"}
23:22:49.549 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.551 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.554 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 23% complete"}
23:22:49.563 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.564 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.568 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.569 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.570 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.572 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.572 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.573 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.575 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 23% complete\r Applying namespace: 23% complete"}
23:22:49.580 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.581 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 23% complete"}
23:22:49.589 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.590 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.591 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.591 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.597 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.598 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.599 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.600 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.608 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.609 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.617 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.618 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.619 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 24% complete"}
23:22:49.625 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.626 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.626 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 24% complete"}
23:22:49.626 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.626 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.637 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.638 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.639 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.641 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete"}
23:22:49.642 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.643 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 24% complete\r Applying namespace: 24% complete\r Applying namespace: 24% complete\r Applying namespace: 24% complete"}
23:22:49.645 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.646 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.649 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 25% complete"}
23:22:49.650 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.650 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.656 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.659 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete\r"}
23:22:49.660 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.664 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 25% complete"}
23:22:49.666 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.666 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.667 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.669 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.673 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 25% complete\r Applying namespace: 25% complete\r Applying namespace: 25% complete"}
23:22:49.673 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.674 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.680 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.681 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.682 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.684 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.684 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.684 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.684 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.684 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 25% complete"}
23:22:49.686 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.687 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.691 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.691 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.691 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.691 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.691 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.693 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.696 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 26% complete"}
23:22:49.701 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.701 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.702 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.703 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.704 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 26% complete"}
23:22:49.708 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.711 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.714 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.714 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.728 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.732 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.740 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.741 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.743 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.744 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete"}
23:22:49.751 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.754 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 26% complete\r Applying namespace: 26% complete\r Applying namespace: 26% complete"}
23:22:49.757 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 27% complete"}
23:22:49.759 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.760 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete\r Applying namespace: 27% complete"}
23:22:49.760 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 27% complete"}
23:22:49.765 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.766 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.766 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.766 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.766 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 27% complete"}
23:22:49.766 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.766 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.767 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 27% complete"}
23:22:49.791 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.793 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.798 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.799 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.804 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.805 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.810 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 27% complete"}
23:22:49.814 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.818 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete\r Applying namespace: 27% complete"}
23:22:49.819 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.819 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 27% complete"}
23:22:49.827 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.827 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete"}
23:22:49.829 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 28% complete"}
23:22:49.832 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.833 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete"}
23:22:49.836 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.838 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete"}
23:22:49.840 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.841 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete"}
23:22:49.848 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.852 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete\r Applying namespace: 28% complete\r Applying namespace: 28% complete"}
23:22:49.860 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.860 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete"}
23:22:49.862 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.865 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete\r Applying namespace: 28% complete\r"}
23:22:49.865 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete"}
23:22:49.874 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 28% complete"}
23:22:49.875 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.882 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 28% complete\r Applying namespace: 28% complete\r Applying namespace: 28% complete"}
23:22:49.884 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.885 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 29% complete\r"}
23:22:49.886 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 29% complete"}
23:22:49.889 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 29% complete"}
23:22:49.891 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.895 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 29% complete\r Applying namespace: 29% complete\r"}
23:22:49.896 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 29% complete\r Applying namespace: 29% complete\r Applying namespace: 29% complete"}
23:22:49.901 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.903 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 29% complete\r Applying namespace: 29% complete\r Applying namespace: 29% complete"}
23:22:49.905 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 29% complete"}
23:22:49.905 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 29% complete\r Applying namespace: 29% complete"}
23:22:49.906 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.906 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 29% complete"}
23:22:49.908 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.910 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete"}
23:22:49.911 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 30% complete"}
23:22:49.912 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 30% complete"}
23:22:49.912 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.913 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete"}
23:22:49.914 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.916 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete\r Applying namespace: 30% complete"}
23:22:49.921 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.922 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete\r"}
23:22:49.923 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete"}
23:22:49.931 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.934 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete\r"}
23:22:49.935 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete\r"}
23:22:49.936 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete"}
23:22:49.940 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.941 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 30% complete\r Applying namespace: 30% complete\r Applying namespace: 30% complete\r Applying namespace: 30% complete\r Applying namespace: 30% complete\r Applying namespace: 31% complete\r Applying namespace: 31% complete"}
23:22:49.942 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.942 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 31% complete"}
23:22:49.943 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 31% complete\r Applying namespace: 31% complete\r Applying namespace: 31% complete"}
23:22:49.944 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.949 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 31% complete"}
23:22:49.950 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 31% complete\r Applying namespace: 31% complete\r"}
23:22:49.952 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 31% complete"}
23:22:49.954 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 31% complete\r Applying namespace: 31% complete\r Applying namespace: 31% complete\r Applying namespace: 31% complete\r Applying namespace: 31% complete\r"}
23:22:49.955 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 31% complete"}
23:22:49.956 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.956 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.956 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 32% complete\r"}
23:22:49.956 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.956 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.958 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.958 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.958 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete\r"}
23:22:49.958 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete\r"}
23:22:49.958 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.958 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.959 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.959 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.962 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.964 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.965 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.968 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 32% complete\r Applying namespace: 32% complete"}
23:22:49.968 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.968 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.970 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.971 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 32% complete"}
23:22:49.975 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 32% complete\r Applying namespace: 32% complete\r Applying namespace: 33% complete"}
23:22:49.976 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.976 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.984 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.985 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.986 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.986 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.986 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.987 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.987 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.987 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.989 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.990 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.991 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.991 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete\r"}
23:22:49.991 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.997 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:49.999 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:49.999 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.000 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:50.000 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.000 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete\r"}
23:22:50.000 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:50.001 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.001 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:50.004 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.005 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:50.006 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.008 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 33% complete"}
23:22:50.009 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 34% complete\r Applying namespace: 34% complete"}
23:22:50.009 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.009 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.013 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.014 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.015 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.015 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.015 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.015 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.019 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.024 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.025 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.025 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.025 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.026 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete\r"}
23:22:50.026 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.026 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.027 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete\r Applying namespace: 34% complete\r Applying namespace: 34% complete"}
23:22:50.030 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.030 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.031 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.031 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.032 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.032 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 34% complete"}
23:22:50.033 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.034 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 35% complete"}
23:22:50.036 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.038 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 35% complete\r Applying namespace: 35% complete\r Applying namespace: 35% complete\r Applying namespace: 35% complete\r Applying namespace: 35% complete"}
23:22:50.038 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 35% complete"}
23:22:50.041 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.043 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 35% complete"}
23:22:50.050 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 35% complete\r Applying namespace: 35% complete"}
23:22:50.051 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 35% complete"}
23:22:50.054 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 35% complete\r"}
23:22:50.054 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 35% complete"}
23:22:50.055 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.056 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 35% complete\r Applying namespace: 35% complete"}
23:22:50.057 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.058 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 35% complete"}
23:22:50.061 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.064 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete\r Applying namespace: 36% complete"}
23:22:50.065 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 36% complete\r"}
23:22:50.065 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete\r Applying namespace: 36% complete\r"}
23:22:50.065 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete\r Applying namespace: 36% complete"}
23:22:50.065 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.065 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.066 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 36% complete"}
23:22:50.069 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.069 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.073 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.075 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.076 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.084 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.085 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.086 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.086 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.087 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.087 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 36% complete"}
23:22:50.088 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.088 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 36% complete"}
23:22:50.089 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 37% complete\r Applying namespace: 37% complete\r Applying namespace: 37% complete\r"}
23:22:50.089 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 37% complete\r Applying namespace: 37% complete\r Applying namespace: 37% complete\r Applying namespace: 37% complete"}
23:22:50.089 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 37% complete\r Applying namespace: 37% complete"}
23:22:50.089 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 37% complete\r"}
23:22:50.089 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 37% complete"}
23:22:50.090 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 37% complete\r Applying namespace: 37% complete"}
23:22:50.091 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.092 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 37% complete"}
23:22:50.093 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.093 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 37% complete\r Applying namespace: 37% complete"}
23:22:50.094 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.094 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete\r"}
23:22:50.094 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete"}
23:22:50.096 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.097 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete\r Applying namespace: 38% complete\r Applying namespace: 38% complete\r Applying namespace: 38% complete\r"}
23:22:50.098 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete"}
23:22:50.099 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.099 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete\r Applying namespace: 38% complete"}
23:22:50.099 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.099 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete"}
23:22:50.099 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 38% complete\r Applying namespace: 38% complete"}
23:22:50.102 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.103 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete"}
23:22:50.104 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.104 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete"}
23:22:50.105 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 38% complete"}
23:22:50.105 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.106 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 38% complete"}
23:22:50.107 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.107 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.108 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.108 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.109 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.109 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete\r"}
23:22:50.109 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete\r Applying namespace: 39% complete\r Applying namespace: 39% complete"}
23:22:50.109 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.110 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete\r Applying namespace: 39% complete\r"}
23:22:50.112 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.113 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.114 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.114 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 39% complete\r"}
23:22:50.115 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.115 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.115 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.116 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.117 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.118 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.119 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.119 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.121 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 39% complete"}
23:22:50.121 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.121 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.122 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.122 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.123 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.123 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.124 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.124 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.124 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.124 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete\r"}
23:22:50.124 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.125 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.125 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete\r Applying namespace: 40% complete"}
23:22:50.125 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 40% complete\r"}
23:22:50.125 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete\r Applying namespace: 40% complete"}
23:22:50.126 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 40% complete"}
23:22:50.126 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.126 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.126 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.127 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.127 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.127 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.128 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.128 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 40% complete"}
23:22:50.128 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 41% complete"}
23:22:50.128 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.128 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.129 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.129 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete\r Applying namespace: 41% complete"}
23:22:50.129 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 41% complete\r"}
23:22:50.129 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.129 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 41% complete\r"}
23:22:50.130 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.130 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 41% complete"}
23:22:50.130 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.131 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.131 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.132 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.132 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.133 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.134 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.135 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.136 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 41% complete"}
23:22:50.136 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.137 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.137 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.138 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 41% complete"}
23:22:50.138 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.138 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.140 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.140 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.141 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.142 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.143 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.143 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.144 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.147 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.148 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 42% complete\r Applying namespace: 42% complete"}
23:22:50.148 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.149 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.149 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.149 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete\r"}
23:22:50.150 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete\r"}
23:22:50.153 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete\r Applying namespace: 42% complete\r Applying namespace: 42% complete\r Applying namespace: 42% complete"}
23:22:50.154 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.155 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.157 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.158 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 42% complete"}
23:22:50.158 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 43% complete"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 43% complete"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.159 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 43% complete"}
23:22:50.160 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.164 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete\r"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 43% complete\r Applying namespace: 43% complete\r"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 43% complete\r"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete\r"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 43% complete"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.165 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.166 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.166 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.166 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.166 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.168 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.168 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.168 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.168 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.169 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.169 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.169 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 44% complete"}
23:22:50.169 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.170 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.170 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.171 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.171 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.171 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete\r"}
23:22:50.171 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.172 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.173 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete"}
23:22:50.174 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 44% complete\r"}
23:22:50.176 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 44% complete\r Applying namespace: 44% complete\r Applying namespace: 44% complete\r Applying namespace: 45% complete\r Applying namespace: 45% complete"}
23:22:50.176 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.177 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.177 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.177 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.177 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.178 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete\r"}
23:22:50.179 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete\r"}
23:22:50.180 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.181 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.181 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.182 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.182 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.182 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.184 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.184 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.185 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete\r"}
23:22:50.186 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete"}
23:22:50.188 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.188 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete\r Applying namespace: 45% complete\r"}
23:22:50.188 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 45% complete\r Applying namespace: 45% complete"}
23:22:50.188 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 46% complete"}
23:22:50.189 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 46% complete"}
23:22:50.189 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.189 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.189 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 46% complete\r"}
23:22:50.190 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.191 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.191 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.192 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.192 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.192 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.193 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.194 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 46% complete\r Applying namespace: 46% complete\r"}
23:22:50.194 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.195 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.195 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.196 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.197 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete\r"}
23:22:50.197 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete"}
23:22:50.197 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.197 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 46% complete\r Applying namespace: 46% complete\r Applying namespace: 47% complete"}
23:22:50.198 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.198 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.199 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.199 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.199 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.200 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.200 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 47% complete\r"}
23:22:50.200 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.200 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.201 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete\r"}
23:22:50.202 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.203 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.203 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete\r Applying namespace: 47% complete"}
23:22:50.204 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.204 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.204 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.205 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.205 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.206 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete\r Applying namespace: 47% complete\r"}
23:22:50.206 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.206 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.207 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 47% complete"}
23:22:50.207 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 48% complete\r"}
23:22:50.207 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete"}
23:22:50.208 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.208 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete\r"}
23:22:50.208 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete"}
23:22:50.208 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 48% complete"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 48% complete"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete\r Applying namespace: 48% complete"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 48% complete\r Applying namespace: 48% complete"}
23:22:50.209 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.210 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete\r"}
23:22:50.210 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete\r Applying namespace: 48% complete"}
23:22:50.210 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 48% complete\r"}
23:22:50.211 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 48% complete"}
23:22:50.211 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.211 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete\r Applying namespace: 49% complete\r Applying namespace: 49% complete"}
23:22:50.211 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.211 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete"}
23:22:50.212 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 49% complete"}
23:22:50.212 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 49% complete"}
23:22:50.212 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.212 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete\r Applying namespace: 49% complete\r Applying namespace: 49% complete"}
23:22:50.212 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.212 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete"}
23:22:50.213 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.213 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete\r"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 49% complete"}
23:22:50.215 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.217 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 49% complete\r Applying namespace: 50% complete"}
23:22:50.217 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.222 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete\r"}
23:22:50.222 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.222 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.224 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.225 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.226 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.226 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.226 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.226 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 50% complete"}
23:22:50.227 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.227 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.228 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.228 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.228 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.229 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.229 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.230 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete"}
23:22:50.230 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 50% complete\r Applying namespace: 50% complete\r"}
23:22:50.230 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete\r Applying namespace: 50% complete\r"}
23:22:50.230 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 50% complete\r Applying namespace: 51% complete\r"}
23:22:50.230 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete"}
23:22:50.231 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.231 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete"}
23:22:50.231 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.231 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete"}
23:22:50.232 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.232 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete"}
23:22:50.232 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.232 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete"}
23:22:50.233 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.233 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete"}
23:22:50.236 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 51% complete\r Applying namespace: 51% complete\r Applying namespace: 51% complete\r Applying namespace: 51% complete"}
23:22:50.237 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.241 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 51% complete\r Applying namespace: 51% complete\r Applying namespace: 51% complete\r Applying namespace: 51% complete\r Applying namespace: 51% complete"}
23:22:50.242 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.242 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.242 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.243 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.243 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.243 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.243 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.244 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete\r"}
23:22:50.245 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.246 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.246 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.248 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.248 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.248 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.249 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.249 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.249 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.250 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.251 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.251 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 52% complete\r"}
23:22:50.251 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete"}
23:22:50.251 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 52% complete"}
23:22:50.252 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.252 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 52% complete\r Applying namespace: 52% complete\r Applying namespace: 52% complete"}
23:22:50.253 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.253 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete"}
23:22:50.253 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.254 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete"}
23:22:50.254 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 53% complete\r Applying namespace: 53% complete\r"}
23:22:50.254 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete\r"}
23:22:50.254 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete\r"}
23:22:50.254 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete"}
23:22:50.254 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 53% complete\r Applying namespace: 53% complete"}
23:22:50.255 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.255 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete\r Applying namespace: 53% complete"}
23:22:50.255 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.255 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete\r Applying namespace: 53% complete\r Applying namespace: 53% complete\r"}
23:22:50.255 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete\r"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 53% complete"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 54% complete\r Applying namespace: 54% complete\r"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 54% complete"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 54% complete\r Applying namespace: 54% complete\r"}
23:22:50.256 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 54% complete\r"}
23:22:50.257 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 54% complete"}
23:22:50.257 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.257 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 54% complete\r Applying namespace: 54% complete"}
23:22:50.257 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 54% complete"}
23:22:50.258 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 54% complete\r Applying namespace: 54% complete\r Applying namespace: 54% complete\r Applying namespace: 54% complete\r Applying namespace: 54% complete\r Applying namespace: 54% complete"}
23:22:50.258 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 55% complete\r Applying namespace: 55% complete"}
23:22:50.258 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 55% complete"}
23:22:50.259 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.259 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.259 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 55% complete\r"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.260 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete\r"}
23:22:50.261 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.261 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.262 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.262 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 55% complete"}
23:22:50.262 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 55% complete"}
23:22:50.262 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.263 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.263 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.264 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete\r"}
23:22:50.264 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 55% complete"}
23:22:50.264 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.264 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.264 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 56% complete"}
23:22:50.265 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.266 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.266 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.266 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.267 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 56% complete\r Applying namespace: 56% complete"}
23:22:50.267 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.267 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.268 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.268 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.269 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.269 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.270 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.270 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.271 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.271 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.275 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.275 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete\r Applying namespace: 56% complete\r"}
23:22:50.275 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 56% complete"}
23:22:50.276 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 56% complete"}
23:22:50.281 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 56% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 57% complete\r"}
23:22:50.281 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 57% complete\r"}
23:22:50.281 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 57% complete"}
23:22:50.281 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 57% complete"}
23:22:50.282 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.282 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 57% complete"}
23:22:50.284 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.286 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 57% complete\r Applying namespace: 57% complete\r Applying namespace: 58% complete\r Applying namespace: 58% complete\r Applying namespace: 58% complete\r Applying namespace: 58% complete\r Applying namespace: 58% complete\r Applying namespace: 58% complete\r"}
23:22:50.286 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 58% complete"}
23:22:50.287 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 58% complete"}
23:22:50.287 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 58% complete"}
23:22:50.287 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.287 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 58% complete"}
23:22:50.288 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.289 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 58% complete"}
23:22:50.289 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.291 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 58% complete\r"}
23:22:50.291 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 58% complete\r Applying namespace: 58% complete"}
23:22:50.291 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.293 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 58% complete\r Applying namespace: 58% complete\r Applying namespace: 59% complete\r Applying namespace: 59% complete\r Applying namespace: 59% complete"}
23:22:50.295 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.297 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete\r"}
23:22:50.301 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete\r Applying namespace: 59% complete\r Applying namespace: 59% complete\r Applying namespace: 59% complete\r Applying namespace: 59% complete"}
23:22:50.302 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 59% complete"}
23:22:50.303 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.303 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete\r"}
23:22:50.303 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete"}
23:22:50.304 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.304 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete"}
23:22:50.304 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.305 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete"}
23:22:50.305 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.306 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete"}
23:22:50.306 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.306 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 59% complete"}
23:22:50.306 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.307 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.307 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.308 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.308 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.308 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete\r Applying namespace: 60% complete\r"}
23:22:50.308 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.308 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 60% complete"}
23:22:50.308 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.309 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.309 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.310 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.310 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.314 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete\r Applying namespace: 60% complete\r Applying namespace: 60% complete\r Applying namespace: 60% complete\r Applying namespace: 60% complete"}
23:22:50.315 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 60% complete"}
23:22:50.315 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.315 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.317 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.319 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 60% complete"}
23:22:50.319 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.320 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.320 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.321 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.321 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.322 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.323 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.324 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.324 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.325 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.325 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 61% complete"}
23:22:50.325 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 61% complete\r"}
23:22:50.325 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.325 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.326 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.326 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.327 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.328 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.328 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.329 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.330 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.330 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.331 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.331 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.332 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete"}
23:22:50.332 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.333 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 61% complete\r Applying namespace: 61% complete\r"}
23:22:50.333 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete\r"}
23:22:50.333 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete\r"}
23:22:50.334 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete"}
23:22:50.335 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 62% complete"}
23:22:50.335 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.335 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete"}
23:22:50.336 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.337 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete"}
23:22:50.337 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 62% complete"}
23:22:50.338 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.339 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete"}
23:22:50.340 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.341 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete"}
23:22:50.341 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.341 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete\r"}
23:22:50.341 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete\r Applying namespace: 62% complete"}
23:22:50.343 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.343 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete\r"}
23:22:50.343 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete"}
23:22:50.343 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.344 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 62% complete\r Applying namespace: 62% complete"}
23:22:50.344 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.345 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 63% complete"}
23:22:50.346 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.350 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete\r Applying namespace: 63% complete"}
23:22:50.350 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.351 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 63% complete\r"}
23:22:50.352 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 63% complete\r Applying namespace: 63% complete"}
23:22:50.352 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.352 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete\r Applying namespace: 64% complete"}
23:22:50.353 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 64% complete\r Applying namespace: 64% complete\r"}
23:22:50.353 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete"}
23:22:50.354 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.354 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete"}
23:22:50.354 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 64% complete"}
23:22:50.355 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.355 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete"}
23:22:50.355 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 64% complete"}
23:22:50.355 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 64% complete\r"}
23:22:50.356 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete\r"}
23:22:50.356 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete"}
23:22:50.356 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.359 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 64% complete\r Applying namespace: 64% complete\r Applying namespace: 64% complete\r Applying namespace: 64% complete\r Applying namespace: 65% complete\r Applying namespace: 65% complete\r Applying namespace: 65% complete\r Applying namespace: 65% complete\r"}
23:22:50.360 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 65% complete"}
23:22:50.360 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 65% complete"}
23:22:50.361 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.361 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 65% complete"}
23:22:50.361 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.362 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 65% complete\r Applying namespace: 65% complete\r Applying namespace: 65% complete"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 65% complete\r Applying namespace: 65% complete\r"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 65% complete\r Applying namespace: 65% complete\r"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 65% complete\r Applying namespace: 65% complete\r"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete\r"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete\r Applying namespace: 66% complete"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.363 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.364 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.365 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.365 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 66% complete"}
23:22:50.365 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.366 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.366 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.366 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.367 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.367 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.368 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.370 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.370 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.370 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete\r"}
23:22:50.371 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.371 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.371 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete\r"}
23:22:50.372 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete\r Applying namespace: 66% complete"}
23:22:50.373 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.373 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 66% complete"}
23:22:50.374 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.374 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.376 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.376 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete\r"}
23:22:50.376 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.376 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.377 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.377 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 67% complete"}
23:22:50.377 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.377 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.377 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.378 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.379 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.380 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.381 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.381 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.383 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.383 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete\r Applying namespace: 67% complete\r"}
23:22:50.383 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.383 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.384 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.384 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.385 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete"}
23:22:50.385 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 67% complete"}
23:22:50.386 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.386 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 67% complete\r"}
23:22:50.387 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete"}
23:22:50.387 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.388 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete\r Applying namespace: 68% complete"}
23:22:50.388 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 68% complete"}
23:22:50.388 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 68% complete\r"}
23:22:50.388 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete\r Applying namespace: 68% complete"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete\r Applying namespace: 68% complete\r"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.389 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 68% complete"}
23:22:50.390 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 68% complete\r Applying namespace: 68% complete"}
23:22:50.390 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 68% complete\r Applying namespace: 68% complete"}
23:22:50.390 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.390 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.390 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.391 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.392 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.392 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.392 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.392 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.393 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.393 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.393 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.393 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.394 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 69% complete"}
23:22:50.394 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.397 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete\r Applying namespace: 69% complete\r"}
23:22:50.398 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.398 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.399 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 69% complete"}
23:22:50.400 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 69% complete\r Applying namespace: 69% complete\r Applying namespace: 69% complete\r Applying namespace: 69% complete"}
23:22:50.400 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 69% complete\r"}
23:22:50.400 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.400 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.401 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.401 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.402 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.402 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.402 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.403 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.403 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.403 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.404 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.404 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.404 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete\r"}
23:22:50.404 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.405 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.405 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.406 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.406 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.406 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 70% complete"}
23:22:50.406 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.407 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.407 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 70% complete\r"}
23:22:50.407 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete\r"}
23:22:50.407 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 70% complete"}
23:22:50.407 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 70% complete\r"}
23:22:50.407 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 71% complete\r Applying namespace: 71% complete\r Applying namespace: 71% complete\r Applying namespace: 71% complete\r Applying namespace: 71% complete\r"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 71% complete\r Applying namespace: 71% complete"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 71% complete"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 71% complete\r Applying namespace: 71% complete\r Applying namespace: 71% complete\r Applying namespace: 71% complete"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 71% complete\r"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 71% complete"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 71% complete\r Applying namespace: 72% complete"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 72% complete\r Applying namespace: 72% complete\r Applying namespace: 72% complete"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 72% complete\r Applying namespace: 72% complete\r"}
23:22:50.408 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.409 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.409 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.409 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 72% complete"}
23:22:50.410 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.410 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.415 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.416 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.418 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.418 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.419 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.419 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.419 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 72% complete\r"}
23:22:50.419 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.420 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.420 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 72% complete"}
23:22:50.420 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.420 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.421 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.421 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.421 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.422 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.422 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.422 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete\r Applying namespace: 73% complete"}
23:22:50.422 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 73% complete"}
23:22:50.422 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.422 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.423 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 73% complete\r Applying namespace: 73% complete"}
23:22:50.423 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.424 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.424 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.425 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.425 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.425 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.426 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.426 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.426 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.427 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete\r"}
23:22:50.427 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.427 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.428 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 73% complete"}
23:22:50.428 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.428 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete\r"}
23:22:50.429 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.429 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 74% complete"}
23:22:50.429 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.429 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete\r"}
23:22:50.429 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.429 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.430 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.430 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.430 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.430 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.430 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete\r"}
23:22:50.431 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete\r Applying namespace: 74% complete\r"}
23:22:50.432 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.432 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.432 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.432 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.432 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.432 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.433 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete\r"}
23:22:50.433 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.433 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.434 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 74% complete"}
23:22:50.434 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.434 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.434 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.435 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.435 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 75% complete"}
23:22:50.436 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.436 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.436 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.437 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.437 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.438 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.438 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.438 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.439 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.439 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.439 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.439 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.439 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.439 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.440 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.440 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.441 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.442 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.443 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.446 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 75% complete"}
23:22:50.446 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.447 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete\r Applying namespace: 76% complete"}
23:22:50.447 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 76% complete"}
23:22:50.448 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 76% complete\r"}
23:22:50.448 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete"}
23:22:50.448 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.448 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete"}
23:22:50.449 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.449 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete"}
23:22:50.451 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.452 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete\r Applying namespace: 76% complete"}
23:22:50.453 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.453 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete"}
23:22:50.453 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.453 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete\r"}
23:22:50.454 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete\r Applying namespace: 76% complete\r"}
23:22:50.454 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete\r"}
23:22:50.454 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete"}
23:22:50.454 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.454 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 76% complete"}
23:22:50.455 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.455 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.455 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.456 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.456 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.456 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.456 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.456 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.457 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.457 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.457 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.458 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.458 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.458 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.459 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.459 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete"}
23:22:50.460 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.461 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete\r Applying namespace: 77% complete"}
23:22:50.461 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 77% complete\r Applying namespace: 77% complete\r"}
23:22:50.463 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete\r Applying namespace: 77% complete"}
23:22:50.464 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.466 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 77% complete\r Applying namespace: 77% complete\r"}
23:22:50.466 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete\r"}
23:22:50.467 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete\r"}
23:22:50.467 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete\r"}
23:22:50.468 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete"}
23:22:50.468 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.469 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete"}
23:22:50.469 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.469 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete\r Applying namespace: 78% complete\r Applying namespace: 78% complete\r"}
23:22:50.469 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete\r Applying namespace: 78% complete\r Applying namespace: 78% complete"}
23:22:50.469 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 78% complete"}
23:22:50.469 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.470 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete\r"}
23:22:50.470 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete"}
23:22:50.471 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.471 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete"}
23:22:50.472 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.473 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 78% complete"}
23:22:50.473 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.473 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.474 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.474 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.475 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.475 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.476 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.476 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.476 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.477 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.477 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.477 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.477 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.478 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.479 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.479 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete"}
23:22:50.481 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 79% complete\r Applying namespace: 79% complete\r"}
23:22:50.483 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 79% complete\r Applying namespace: 79% complete\r Applying namespace: 79% complete\r Applying namespace: 79% complete\r Applying namespace: 79% complete\r Applying namespace: 79% complete\r Applying namespace: 80% complete\r Applying namespace: 80% complete\r Applying namespace: 80% complete\r Applying namespace: 80% complete"}
23:22:50.483 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.483 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 80% complete"}
23:22:50.483 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 80% complete"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 80% complete"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 80% complete"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 80% complete"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.484 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 80% complete\r Applying namespace: 80% complete\r Applying namespace: 80% complete\r Applying namespace: 80% complete\r Applying namespace: 80% complete"}
23:22:50.489 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.490 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 80% complete"}
23:22:50.491 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.492 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 80% complete"}
23:22:50.498 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete"}
23:22:50.499 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 81% complete\r Applying namespace: 82% complete"}
23:22:50.499 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.500 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete"}
23:22:50.500 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.501 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete"}
23:22:50.501 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.502 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete"}
23:22:50.502 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.503 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete"}
23:22:50.504 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.506 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete\r"}
23:22:50.507 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete\r Applying namespace: 82% complete\r Applying namespace: 82% complete\r Applying namespace: 82% complete\r Applying namespace: 82% complete"}
23:22:50.509 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 82% complete\r Applying namespace: 82% complete"}
23:22:50.511 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.512 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete\r"}
23:22:50.517 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 82% complete\r Applying namespace: 82% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r"}
23:22:50.518 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 83% complete"}
23:22:50.519 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.520 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 83% complete"}
23:22:50.524 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete\r Applying namespace: 83% complete"}
23:22:50.525 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 83% complete"}
23:22:50.525 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.525 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 83% complete\r Applying namespace: 84% complete"}
23:22:50.526 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.526 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.526 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 84% complete"}
23:22:50.526 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 84% complete"}
23:22:50.526 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.527 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.527 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.527 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.527 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.528 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.528 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.528 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.529 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.529 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.530 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.531 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.532 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.532 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.533 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.534 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.534 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.537 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete\r Applying namespace: 84% complete\r Applying namespace: 84% complete\r"}
23:22:50.538 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 84% complete"}
23:22:50.538 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 85% complete"}
23:22:50.538 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.538 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 85% complete"}
23:22:50.538 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.538 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 85% complete"}
23:22:50.540 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.541 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 85% complete"}
23:22:50.542 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.543 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 85% complete"}
23:22:50.544 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.545 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 85% complete\r Applying namespace: 85% complete\r Applying namespace: 85% complete\r Applying namespace: 85% complete\r Applying namespace: 85% complete\r Applying namespace: 85% complete\r Applying namespace: 85% complete"}
23:22:50.545 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 85% complete\r Applying namespace: 85% complete\r Applying namespace: 85% complete\r"}
23:22:50.545 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 85% complete"}
23:22:50.545 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.545 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 86% complete\r"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete\r"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.546 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.547 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.548 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.548 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.548 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.549 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.549 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.550 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.551 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.552 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete\r"}
23:22:50.552 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.553 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.554 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 86% complete"}
23:22:50.562 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 86% complete\r Applying namespace: 86% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 87% complete\r Applying namespace: 88% complete\r Applying namespace: 88% complete\r Applying namespace: 88% complete\r"}
23:22:50.563 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete\r Applying namespace: 88% complete\r Applying namespace: 88% complete\r Applying namespace: 88% complete\r"}
23:22:50.563 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete\r Applying namespace: 88% complete"}
23:22:50.564 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 88% complete"}
23:22:50.564 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.566 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete"}
23:22:50.572 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.572 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete"}
23:22:50.573 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.573 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete"}
23:22:50.574 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.574 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete"}
23:22:50.575 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.582 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 88% complete\r Applying namespace: 88% complete\r Applying namespace: 89% complete\r Applying namespace: 89% complete\r"}
23:22:50.584 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete"}
23:22:50.584 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 89% complete\r Applying namespace: 89% complete"}
23:22:50.585 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 89% complete\r"}
23:22:50.585 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete"}
23:22:50.585 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 89% complete"}
23:22:50.585 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.590 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete\r Applying namespace: 89% complete\r"}
23:22:50.590 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete"}
23:22:50.591 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.592 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete"}
23:22:50.593 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.594 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete"}
23:22:50.597 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 89% complete"}
23:22:50.599 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.605 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 89% complete"}
23:22:50.607 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 89% complete\r Applying namespace: 90% complete"}
23:22:50.608 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 90% complete"}
23:22:50.609 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.619 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete\r"}
23:22:50.619 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete"}
23:22:50.621 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.624 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete"}
23:22:50.626 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.631 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete"}
23:22:50.633 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 90% complete\r"}
23:22:50.634 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete"}
23:22:50.634 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 90% complete"}
23:22:50.636 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 90% complete"}
23:22:50.637 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.638 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete\r Applying namespace: 90% complete"}
23:22:50.639 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.640 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete"}
23:22:50.642 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.644 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete\r"}
23:22:50.648 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete\r"}
23:22:50.649 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 90% complete"}
23:22:50.650 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.651 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 91% complete"}
23:22:50.654 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.659 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 91% complete\r Applying namespace: 91% complete\r Applying namespace: 91% complete"}
23:22:50.660 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.660 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 91% complete"}
23:22:50.662 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 91% complete\r"}
23:22:50.663 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 91% complete\r Applying namespace: 91% complete"}
23:22:50.663 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.663 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 91% complete"}
23:22:50.665 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.667 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 91% complete"}
23:22:50.671 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 91% complete\r Applying namespace: 91% complete\r Applying namespace: 91% complete\r Applying namespace: 91% complete\r Applying namespace: 91% complete\r Applying namespace: 91% complete\r Applying namespace: 92% complete\r"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 92% complete"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 92% complete\r Applying namespace: 92% complete\r"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete\r Applying namespace: 92% complete\r Applying namespace: 92% complete"}
23:22:50.672 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 92% complete\r"}
23:22:50.673 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.673 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.673 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.674 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.674 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.675 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.676 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.676 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 92% complete"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete\r Applying namespace: 93% complete\r Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete\r Applying namespace: 93% complete\r Applying namespace: 93% complete\r Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete\r Applying namespace: 93% complete\r Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete\r Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 93% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 94% complete\r Applying namespace: 94% complete\r Applying namespace: 94% complete"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 94% complete\r Applying namespace: 94% complete"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 94% complete\r Applying namespace: 94% complete"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 94% complete\r"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 94% complete\r Applying namespace: 94% complete"}
23:22:50.677 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 94% complete\r Applying namespace: 94% complete"}
23:22:50.678 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 94% complete\r"}
23:22:50.678 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 94% complete\r Applying namespace: 94% complete\r"}
23:22:50.678 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 94% complete\r Applying namespace: 95% complete\r"}
23:22:50.678 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.684 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 95% complete\r Applying namespace: 95% complete\r Applying namespace: 95% complete"}
23:22:50.685 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.687 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.688 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.689 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.691 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.692 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.692 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.693 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.696 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.697 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.699 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.701 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete\r"}
23:22:50.701 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.701 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.701 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete"}
23:22:50.706 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.708 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 95% complete\r Applying namespace: 95% complete\r Applying namespace: 95% complete\r"}
23:22:50.710 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.711 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.711 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.724 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.725 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.726 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.727 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.731 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 96% complete\r"}
23:22:50.731 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.732 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.733 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.734 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.735 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.736 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.737 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.739 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 96% complete"}
23:22:50.739 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.741 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.742 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.743 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.746 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.747 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.747 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.748 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.749 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.750 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete"}
23:22:50.751 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.754 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 96% complete\r Applying namespace: 97% complete\r"}
23:22:50.757 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete\r Applying namespace: 97% complete"}
23:22:50.758 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.759 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.763 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 97% complete"}
23:22:50.764 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 97% complete\r"}
23:22:50.764 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.765 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.767 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.768 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.769 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.770 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.770 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.770 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.771 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete\r"}
23:22:50.771 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.771 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.772 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.774 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.775 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.777 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.777 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.780 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.781 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 97% complete"}
23:22:50.782 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 98% complete"}
23:22:50.785 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.786 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.787 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 98% complete"}
23:22:50.789 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.791 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.792 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.800 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete\r Applying namespace: 98% complete\r Applying namespace: 98% complete\r Applying namespace: 98% complete"}
23:22:50.801 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.802 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.812 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.812 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.813 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.815 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.816 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.816 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.818 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 98% complete"}
23:22:50.819 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 98% complete"}
23:22:50.825 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.825 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.840 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.842 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 98% complete"}
23:22:50.843 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.843 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.852 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.852 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.865 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.866 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.870 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.870 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.876 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.877 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.881 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.882 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.889 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.889 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.899 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.899 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete\r Applying namespace: 99% complete"}
23:22:50.904 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.904 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.963 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.963 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:50.995 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:50.995 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:51.025 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:51.025 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:51.028 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 99% complete"}
23:22:51.035 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:51.035 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete"}
23:22:51.049 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:51.050 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 99% complete\r Applying namespace: 100% complete"}
23:22:51.084 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 100% complete"}
23:22:51.108 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:51.108 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 100% complete"}
23:22:51.117 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:51.117 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 100% complete"}
23:22:51.151 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 100% complete"}
23:22:51.434 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 100% complete"}
23:22:51.622 [info] wait_for_engine -> data: {:data, ~c"\r Applying namespace: 100% complete"}
23:22:53.331 [info] wait_for_engine -> data: {:data, ~c"\r"}
23:22:53.331 [info] wait_for_engine -> data: {:data, ~c" Applying namespace: 100% complete\n done\n"}
23:22:53.331 [info] wait_for_engine -> data: {:data, ~c"Rewriting 0 scripts\nRebuilding 0 boot files\n"}
23:22:53.343 [info] wait_for_engine -> data: {:data, ~c"Rewriting 0 config scripts.\n"}
23:22:53.343 [info] Engine build available at: /home/daniel/.local/share/Expert/0.1.0/elixir-1.18.4-erts-16.0.2/70d9f741f61a6eade6974390384e6236/_build/dev_ns
23:22:53.631 [info] Received unexpected port message: ~c"\n23:22:53.626 [notice] Application mix exited: :stopped\n"
23:22:53.687 [info] Received unexpected port message: ~c"\n23:22:53.687 [info] Build path /home/daniel/Developer/my_project/.expert/build/erl-28/elixir-1.18 was compiled on a previous erlang version. Deleting\n"
23:22:53.738 [info] Child {XPExpert.Project.Node, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.195.0>
Start Call: XPExpert.Project.Node.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 2085})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:53.739 [info] Child {XPExpert.Project.Diagnostics, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.208.0>
Start Call: XPExpert.Project.Diagnostics.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 2085})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:53.740 [info] Child {XPExpert.Project.Intelligence, "my_project"} of Supervisor :"my_project::supervisor" started
Pid: #PID<0.209.0>
Start Call: XPExpert.Project.Intelligence.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 2085})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:53.740 [info] Child XPExpert.Project.SearchListener of Supervisor :"my_project::supervisor" started
Pid: #PID<0.210.0>
Start Call: XPExpert.Project.SearchListener.start_link(%XPForge.Project{root_uri: "file:///home/daniel/Developer/my_project", mix_exs_uri: "file:///home/daniel/Developer/my_project/mix.exs", mix_project?: true, mix_env: nil, mix_target: nil, env_variables: %{}, project_module: nil, entropy: 2085})
Restart: :permanent
Shutdown: 5000
Type: :worker
23:22:53.802 [info] Received unexpected port message: ~c"\n23:22:53.801 [warning] Could not connect to hex.pm, dependencies will not be fetched\n"
23:22:53.808 [info] Received unexpected port message: ~c"\n23:22:53.808 [info] Loaded []\n"
23:22:53.839 [info] Received unexpected port message: ~c"Compiling 1 file (.ex)\n"
23:22:53.854 [info] Received unexpected port message: ~c"Generated my_project app\n"
23:22:53.855 [info] Received unexpected port message: ~c"\n23:22:53.855 [info] Compile completed with status ok Produced 0 diagnostics []\n"
23:22:53.858 [info] Compiled my_project in 63 ms
23:22:53.862 [info] Received unexpected port message: ~c"\n23:22:53.862 [info] Plugins found 0 results in 0.029 ms\n"
23:22:53.872 [info] Received unexpected port message: ~c"\n23:22:53.871 [info] backend reports empty\n"
This is odd, does the same happen with OTP 27? OTP 28 is known to be a cursed release for expert, I'm not 100% sure this is the issue but I wouldn't be surprised if it is
Hello, first of all. Amazing work on expert 💯.
I had the same problem describe here, but on a mac m2 and neovim. I tried all the installation methods available. At but at some point I saw this error:
Kernel pid terminated (application_controller) ("{application_start_failure,kernel,{{shutdown,{failed_to_start_child,net_sup,{shutdown,{failed_to_start_child,'Elixir.Livebook.EPMD',{'EXIT',{undef,[{'Elixir.Livebook.EPMD',start_link,[],[]},{supervisor,do_start_child_i,3,[{file,\"supervisor.erl\"},{line,959}]},{supervisor,do_start_child,3,[{file,\"supervisor.erl\"},{line,945}]},{supervisor,'-start_children/2-fun-0-',3,[{file,\"supervisor.erl\"},{line,929}]},{supervisor,children_map,4,[{file,\"supervisor.erl\"},{line,1820}]},{supervisor,init_children,2,[{file,\"supervisor.erl\"},{line,889}]},{gen_server,init_it,2,[{file,\"gen_server.erl\"},{line,2229}]},{gen_server,init_it,6,[{file,\"gen_server.erl\"},{line,2184}]}]}}}}}},{kernel,start,[normal,[]]}}}")
Crash dump is being written to: erl_crash.dump...done
For some reason, I can't remember, I had the
$ELIXIR_ERL_OPTIONS set to -epmd_module Elixir.Livebook.EPMD
After removing this env var, all worked perfectly.
👋 @DFilipeS do you have this env var too?
Update:
On my work computer (m3 pro), I don't see errors, but I stay stuck here:
13:54:10.463 [debug] Child :net_sup_dynamic of Supervisor :kernel_sup started
Pid: #PID<0.173.0>
Start Call: :erl_distribution.start_link(%{name: :"[email protected]", supervisor: :net_sup_dynamic, name_domain: :longnames, clean_halt: false})
Restart: :permanent
Shutdown: 1000
Type: :supervisor
13:54:10.480 [debug] handled request client -> server initialize in 61ms
13:54:10.482 [debug] sent request server -> client client/registerCapability
13:54:10.488 [debug] handled notification client -> server initialized in 7ms
13:54:10.492 [info] ################### opened file:///...file.ex
13:54:10.492 [debug] handled notification client -> server textDocument/didOpen in 4ms
13:54:10.514 [debug] sent notification server -> client window/logMessage
13:54:10.515 [debug] sent request server -> client window/workDoneProgress/create
13:54:10.516 [debug] sent notification server -> client $/progress
For the second problem I mentioned above (got stuck on "Project Node"), I resolved the problem after creating a global asdf file matching the version of my project, as described here: https://github.com/elixir-lang/expert/issues/91
I haven't been able to reproduce for a while and the last engine start errors/no connection errors were fixed in latest main