=== Test case: eturnal_SUITE:start_eturnal/1 (click for source code) === Config value: [{address,{127,0,0,1}}, {udp_port,34780}, {tcp_port,34780}, {tls_port,53490}, {auto_port,34781}, {watchdog,<0.987.0>}, {tc_logfile,"/home/runner/work/eturnal/eturnal/_build/test/logs/ct_run.nonode@nohost.2025-08-21_11.51.40/lib.eturnal.logs/run.2025-08-21_11.51.40/eturnal_suite.start_eturnal.html"}, {tc_group_properties,[]}, {tc_group_path,[]}, {data_dir,"/home/runner/work/eturnal/eturnal/_build/test/lib/eturnal/test/eturnal_SUITE_data/"}, {priv_dir,"/home/runner/work/eturnal/eturnal/_build/test/logs/ct_run.nonode@nohost.2025-08-21_11.51.40/lib.eturnal.logs/run.2025-08-21_11.51.40/log_private/"}] === Current directory is "/home/runner/work/eturnal/eturnal/_build/test/logs/ct_run.nonode@nohost.2025-08-21_11.51.40" === Started at 2025-08-21 11:51:40
*** User 2025-08-21 11:51:40.246 ***🔗 Starting up eturnal
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.293 ***🔗 =NOTICE REPORT==== 21-Aug-2025::11:51:40.293853 === Starting eturnal 1.12.2 on Erlang/OTP 28 (ERTS 16.0)
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.294 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:40.294005 === Starting supervisor: eturnal_sup
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.294 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:40.294118 === Configuring eturnal_sup supervisor: [#{id => systemd, start => {eturnal_systemd,start_link,[]}}, #{id => eturnal, start => {eturnal,start_link,[]}}]
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.295 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:40.295419 === Got no NOTIFY_SOCKET, notifications disabled
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.295 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:40.295636 === Relay IPv4 address: 127.0.0.1 (port range: 49152-65535)
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.295 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:40.295768 === Relay IPv6 address not configured
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.305 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:40.305425 === Cannot determine control query port: noport
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.305 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:40.305764 === Using run directory run
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.305 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:40.305864 === TURN configuration seems fine
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:40.306 ***🔗 =WARNING REPORT==== 21-Aug-2025::11:51:40.305963 === TLS enabled without 'tls_crt_file', creating self-signed certificate
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.537 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.537370 === Calling mod_stats_prometheus:start/0
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.537 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.537648 === Starting mod_stats_prometheus
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.558 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.557930 === Dependency prometheus_httpd was available already
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.605 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.605178 === Started mod_stats_prometheus
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.605 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.605319 === Calling mod_log_stun:start/0
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.605 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.605448 === Starting mod_log_stun
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.605 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.605597 === Started mod_log_stun
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.605 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.605708 === Calling mod_stats_influx:start/0
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.606 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.606161 === Starting mod_stats_influx
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.611 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.611716 === msg: Starting application: influx_udp
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.615 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.615389 === Dependency influx_udp was available already
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.618 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.618129 === options: #{port => 8089,host => "localhost",pool_size => 1, max_overflow => 1} msg: Start clients pool host: {127,0,0,1}
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.629 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.629139 === port: 58913 msg: Open UDP socket on port
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.630 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.629739 === Started mod_stats_influx
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.630 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.630119 === Starting listener 127.0.0.1:34780 (udp) with options: [{use_turn,true}, {auth_type,user}, {turn_ipv4_address,{127,0,0,1}}, {turn_min_port,49152}, {turn_max_port,65535}, {turn_max_allocations,10}, {turn_max_permissions,10}, {shaper,100000}, {auth_realm,<<"eturnal.net">>}, {server_name,<<"eturnal">>}, {auth_fun,fun eturnal:get_password/2}, {hook_fun,fun eturnal:run_hook/2}, {turn_blacklist_clients,[{{127,0,0,1},8}]}, {turn_blacklist_peers,[{{10,0,0,0},8}, {{100,64,0,0},10}, {{127,0,0,0},8}, {{169,254,0,0},16}, {{172,16,0,0},12}, {{192,0,0,0},24}, {{192,0,2,0},24}, {{192,88,99,0},24}, {{192,168,0,0},16}, {{198,18,0,0},15}, {{198,51,100,0},24}, {{203,0,113,0},24}, {{224,0,0,0},4}, {{240,0,0,0},4}, {{0,0,0,0,0,0,0,1},128}, {{100,65435,0,0,0,0,0,0},96}, {{256,0,0,0,0,0,0,0},64}, {{8193,3512,0,0,0,0,0,0},32}, {{64512,0,0,0,0,0,0,0},7}, {{65152,0,0,0,0,0,0,0},10}, {{65280,0,0,0,0,0,0,0},8}]}, {turn_whitelist_clients,[{{127,0,0,1},32}]}, {turn_whitelist_peers,[{{127,0,0,1},32}, {{203,0,113,113},32}, {{8193,3512,0,0,0,0,0,0},64}]}]
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.642 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.642851 === Listening on 127.0.0.1:34780 (udp) (STUN/TURN)
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.643 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.642974 === Starting listener 127.0.0.1:34780 (tcp) with options: [{use_turn,false}, {auth_type,anonymous}, {turn_ipv4_address,{127,0,0,1}}, {turn_min_port,49152}, {turn_max_port,65535}, {turn_max_allocations,10}, {turn_max_permissions,10}, {shaper,100000}, {auth_realm,<<"eturnal.net">>}, {server_name,<<"eturnal">>}, {auth_fun,fun eturnal:get_password/2}, {hook_fun,fun eturnal:run_hook/2}, {turn_blacklist_clients,[{{127,0,0,1},8}]}, {turn_blacklist_peers,[{{10,0,0,0},8}, {{100,64,0,0},10}, {{127,0,0,0},8}, {{169,254,0,0},16}, {{172,16,0,0},12}, {{192,0,0,0},24}, {{192,0,2,0},24}, {{192,88,99,0},24}, {{192,168,0,0},16}, {{198,18,0,0},15}, {{198,51,100,0},24}, {{203,0,113,0},24}, {{224,0,0,0},4}, {{240,0,0,0},4}, {{0,0,0,0,0,0,0,1},128}, {{100,65435,0,0,0,0,0,0},96}, {{256,0,0,0,0,0,0,0},64}, {{8193,3512,0,0,0,0,0,0},32}, {{64512,0,0,0,0,0,0,0},7}, {{65152,0,0,0,0,0,0,0},10}, {{65280,0,0,0,0,0,0,0},8}]}, {turn_whitelist_clients,[{{127,0,0,1},32}]}, {turn_whitelist_peers,[{{127,0,0,1},32}, {{203,0,113,113},32}, {{8193,3512,0,0,0,0,0,0},64}]}]
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.643 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.643626 === Listening on 127.0.0.1:34780 (tcp) (STUN only)
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.644 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.643741 === Starting listener 127.0.0.1:53490 (tls) with options: [{use_turn,false}, {auth_type,anonymous}, {tls,true}, {certfile,<<"run/cert.pem">>}, {ciphers,<<"HIGH:!aNULL:@STRENGTH">>}, {protocol_options,<<"no_tlsv1|no_tlsv1_1|cipher_server_preference">>}, {turn_ipv4_address,{127,0,0,1}}, {turn_min_port,49152}, {turn_max_port,65535}, {turn_max_allocations,10}, {turn_max_permissions,10}, {shaper,100000}, {auth_realm,<<"eturnal.net">>}, {server_name,<<"eturnal">>}, {auth_fun,fun eturnal:get_password/2}, {hook_fun,fun eturnal:run_hook/2}, {turn_blacklist_clients,[{{127,0,0,1},8}]}, {turn_blacklist_peers,[{{10,0,0,0},8}, {{100,64,0,0},10}, {{127,0,0,0},8}, {{169,254,0,0},16}, {{172,16,0,0},12}, {{192,0,0,0},24}, {{192,0,2,0},24}, {{192,88,99,0},24}, {{192,168,0,0},16}, {{198,18,0,0},15}, {{198,51,100,0},24}, {{203,0,113,0},24}, {{224,0,0,0},4}, {{240,0,0,0},4}, {{0,0,0,0,0,0,0,1},128}, {{100,65435,0,0,0,0,0,0},96}, {{256,0,0,0,0,0,0,0},64}, {{8193,3512,0,0,0,0,0,0},32}, {{64512,0,0,0,0,0,0,0},7}, {{65152,0,0,0,0,0,0,0},10}, {{65280,0,0,0,0,0,0,0},8}]}, {turn_whitelist_clients,[{{127,0,0,1},32}]}, {turn_whitelist_peers,[{{127,0,0,1},32}, {{203,0,113,113},32}, {{8193,3512,0,0,0,0,0,0},64}]}]
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.644 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.644293 === Listening on 127.0.0.1:53490 (tls) (STUN only)
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.644 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.644399 === Starting listener 127.0.0.1:34781 (auto) with options: [{use_turn,false}, {auth_type,anonymous}, {tls,optional}, {certfile,<<"run/cert.pem">>}, {ciphers,<<"HIGH:!aNULL:@STRENGTH">>}, {protocol_options,<<"no_tlsv1|no_tlsv1_1|cipher_server_preference">>}, {turn_ipv4_address,{127,0,0,1}}, {turn_min_port,49152}, {turn_max_port,65535}, {turn_max_allocations,10}, {turn_max_permissions,10}, {shaper,100000}, {auth_realm,<<"eturnal.net">>}, {server_name,<<"eturnal">>}, {auth_fun,fun eturnal:get_password/2}, {hook_fun,fun eturnal:run_hook/2}, {turn_blacklist_clients,[{{127,0,0,1},8}]}, {turn_blacklist_peers,[{{10,0,0,0},8}, {{100,64,0,0},10}, {{127,0,0,0},8}, {{169,254,0,0},16}, {{172,16,0,0},12}, {{192,0,0,0},24}, {{192,0,2,0},24}, {{192,88,99,0},24}, {{192,168,0,0},16}, {{198,18,0,0},15}, {{198,51,100,0},24}, {{203,0,113,0},24}, {{224,0,0,0},4}, {{240,0,0,0},4}, {{0,0,0,0,0,0,0,1},128}, {{100,65435,0,0,0,0,0,0},96}, {{256,0,0,0,0,0,0,0},64}, {{8193,3512,0,0,0,0,0,0},32}, {{64512,0,0,0,0,0,0,0},7}, {{65152,0,0,0,0,0,0,0},10}, {{65280,0,0,0,0,0,0,0},8}]}, {turn_whitelist_clients,[{{127,0,0,1},32}]}, {turn_whitelist_peers,[{{127,0,0,1},32}, {{203,0,113,113},32}, {{8193,3512,0,0,0,0,0,0},64}]}]
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.656 ***🔗 =INFO REPORT==== 21-Aug-2025::11:51:41.656351 === Listening on 127.0.0.1:34781 (auto) (STUN only)
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.656 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.656501 === Started 3 modules
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.656 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.656593 === Started 4 listeners
*** System report during eturnal_SUITE:start_eturnal/1 2025-08-21 11:51:41.656 ***🔗 =DEBUG REPORT==== 21-Aug-2025::11:51:41.656840 === No NOTIFY_SOCKET, dropping READY=1 notification
=== Ended at 2025-08-21 11:51:41 === successfully completed test case === === Returned value: ok
Test run history | Top level test index | Latest test result