metalbear-co / mirrord

Connect your local process and your cloud environment, and run local code in cloud conditions.
https://mirrord.dev
MIT License
3.8k stars 103 forks source link

Web Browsing not working - Segmentation fault with microsocks #2351

Closed whispeak-vreixo closed 7 months ago

whispeak-vreixo commented 7 months ago

Bug Description

As discussed with support over discord on 2024/04/04 is not possible to run microsocks with mirrord to follow the steps of Web Browsing tutorial.

If microsocks is running via mirrord the pod and context will be initialized correctly and proxy will start to run. The problem is that on the first network call using the proxy, microsocks and mirrord will crash. The segmentation fault is attached.

Microsocks invoked without mirrord works ok.

Script and brew installs have been tested for both microsocks and mirrord.

Steps to Reproduce

  1. mirrord exec microsocks
  2. curl --socks5 127.0.0.1:1080 google.com
  3. segfault is produced

Backtrace

#0  0x00007fb3a7a902a7 in bincode::varint::decode_unsigned::deserialize_varint_cold_u64 () from /tmp/15951570164723079394-libmirrord_layer.so
#1  0x00007fb3a7a8f146 in bincode::varint::decode_unsigned::varint_decode_u64 () from /tmp/15951570164723079394-libmirrord_layer.so
#2  0x00007fb3a7a8e04b in bincode::de::impls::<impl bincode::de::Decode for u64>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#3  0x00007fb3a7a8cc8b in bincode::de::decode_slice_len () from /tmp/15951570164723079394-libmirrord_layer.so
#4  0x00007fb3a7a918ab in bincode::features::impl_alloc::<impl bincode::de::Decode for alloc::vec::Vec<T>>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#5  0x00007fb3a7b303db in <mirrord_protocol::dns::DnsLookup as bincode::de::Decode>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#6  0x00007fb3a7b99e56 in bincode::de::impls::<impl bincode::de::Decode for core::result::Result<T,U>>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#7  0x00007fb3a7b31a5e in <mirrord_protocol::dns::GetAddrInfoResponse as bincode::de::Decode>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#8  0x00007fb3a7a9905b in <mirrord_intproxy_protocol::ProxyToLayerMessage as bincode::de::Decode>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#9  0x00007fb3a7a9a4cf in <mirrord_intproxy_protocol::LocalMessage<T> as bincode::de::Decode>::decode () from /tmp/15951570164723079394-libmirrord_layer.so
#10 0x00007fb3a7a8ca40 in bincode::decode_from_slice () from /tmp/15951570164723079394-libmirrord_layer.so
#11 0x00007fb3a7a94d4d in mirrord_intproxy_protocol::codec::SyncDecoder<T,R>::receive () from /tmp/15951570164723079394-libmirrord_layer.so
#12 0x00007fb3a7aff828 in mirrord_layer::proxy_connection::ResponseManager::receive () from /tmp/15951570164723079394-libmirrord_layer.so
#13 0x00007fb3a7aff64c in mirrord_layer::proxy_connection::ProxyConnection::receive () from /tmp/15951570164723079394-libmirrord_layer.so
#14 0x00007fb3a7b19764 in mirrord_layer::proxy_connection::ProxyConnection::make_request_with_response::{{closure}} () from /tmp/15951570164723079394-libmirrord_layer.so
#15 0x00007fb3a7b11fe0 in mirrord_layer::proxy_connection::ProxyConnection::make_request_with_response () from /tmp/15951570164723079394-libmirrord_layer.so
#16 0x00007fb3a7b56e87 in mirrord_layer::common::make_proxy_request_with_response () from /tmp/15951570164723079394-libmirrord_layer.so
#17 0x00007fb3a7a3808d in mirrord_layer::socket::ops::remote_getaddrinfo::{{closure}} () from /tmp/15951570164723079394-libmirrord_layer.so
#18 0x00007fb3a7a36b18 in mirrord_layer::socket::ops::remote_getaddrinfo () from /tmp/15951570164723079394-libmirrord_layer.so
#19 0x00007fb3a7a3a561 in mirrord_layer::socket::ops::getaddrinfo::{{closure}} () from /tmp/15951570164723079394-libmirrord_layer.so
#20 0x00007fb3a7a38901 in mirrord_layer::socket::ops::getaddrinfo () from /tmp/15951570164723079394-libmirrord_layer.so
#21 0x00007fb3a7b5da20 in mirrord_layer::socket::hooks::getaddrinfo_detour () from /tmp/15951570164723079394-libmirrord_layer.so
#22 0x00007fb3a8ef4713 in ?? ()
#23 0x0000000000000001 in ?? ()
#24 0x0000000000000001 in ?? ()
#25 0x0000000000000000 in ?? ()

Relevant Logs

RUST_LOG=mirrord=trace mirrord exec microsocks
⠒ mirrord exec
    ✓ Running on latest (3.95.0)!                                                                                                                                                                                  ⠒ mirrord exec
    ✓ Running on latest (3.95.0)!
  ⠚ preparing to launch process
      ✓ layer extracted
    ⠚ checking operator                                                                                                                                                                                            ⠄ mirrord exec
    ✓ Running on latest (3.95.0)!
  ⠂ preparing to launch process
      ✓ layer extracted
      ✓ operator not found                                                                                                                                                                                         2024-04-04T10:42:57.948944Z DEBUG start:create_and_connect: mirrord_kube::api::kubernetes: OpenShift was not detected.
2024-04-04T10:42:57.949069Z TRACE start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore_localhost: false, ignore_ports: {}, mode: Mirror, http_filter: HttpFilterConfig { header_filter: None, path_filter: None, ports: PortList(Multiple([80, 8080])) }, listen_ports: {}, on_concurrent_steal: Abort }, outgoing: OutgoingConfig { tcp: true, udp: true, ignore_localhost: false, filter: None, unix_streams: None }, dns: true }, copy_target: CopyTargetConfig { enabled: false, scale_down: false } }, telemetry: true, kube_context: None, internal_proxy: InternalProxyConfig { start_idle_timeout: 60, idle_timeout: 5, log_level: None, log_destination: None }, use_proxy: true })}:detect_mesh_mirror_mode{incoming_mode=Mirror is_mesh=false}: mirrord_kube::api::kubernetes: return=Ok(())
2024-04-04T10:42:57.949150Z  INFO start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore_localhost: false, ignore_ports: {}, mode: Mirror, http_filter: HttpFilterConfig { header_filter: None, path_filter: None, ports: PortList(Multiple([80, 8080])) }, listen_ports: {}, on_concurrent_steal: Abort }, outgoing: OutgoingConfig { tcp: true, udp: true, ignore_localhost: false, filter: None, unix_streams: None }, dns: true }, copy_target: CopyTargetConfig { enabled: false, scale_down: false } }, telemetry: true, kube_context: None, internal_proxy: InternalProxyConfig { start_idle_timeout: 60, idle_timeout: 5, log_level: None, log_destination: None }, use_proxy: true })}: mirrord_kube::api::kubernetes: Spawning new agent.
2024-04-04T10:42:57.949172Z  INFO start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore_localhost: false, ignore_ports: {}, mode: Mirror, http_filter: HttpFilterConfig { header_filter: None, path_filter: None, ports: PortList(Multiple([80, 8080])) }, listen_ports: {}, on_concurrent_steal: Abort }, outgoing: OutgoingConfig { tcp: true, udp: true, ignore_localhost: false, filter: None, unix_streams: None }, dns: true }, copy_target: CopyTargetConfig { enabled: false, scale_down: false } }, telemetry: true, kube_context: None, internal_proxy: InternalProxyConfig { start_idle_timeout: 60, idle_timeout: 5, log_level: None, log_destination: None }, use_proxy: true })}: mirrord_kube::api::kubernetes: Using port `63511` for communication
2024-04-04T10:42:57.949191Z  INFO start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore⠤ mirrord exec
    ✓ Running on latest (3.95.0)!
  ⠲ preparing to launch process
      ✓ layer extracted
      ✓ operator not found
      ✓ agent pod created
    ⠁ waiting for pod to be ready...                                                                                                                                                                               2024-04-04T10:42:58.103292Z DEBUG start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore_localhost: false, ignore_ports: {}, mode: Mirror, http_filter: HttpFilterConfig { header_filter: None, path_filter: None, ports: PortList(Multiple([80, 8080])) }, listen_ports: {}, on_concurrent_steal: Abort }, outgoing: OutgoingConfig { tcp: true, udp: true, ignore_localhost: false, filter: None, unix_streams: None }, dns: true }, copy_target: CopyTargetConfig { enabled: false, scale_down: false } }, telemetry: true, kube_context: None, internal_proxy: InternalProxyConfig { start_idle_timeout: 60, idle_timeout: 5, log_level: None, log_destination: None }, use_proxy: true })}: mirrord_kube::api::container::job: Pod Phase = "Pending"
2024-04-04T10:42:58.103566Z DEBUG start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore_localhost: false, ignore_ports: {}, mode: Mirror, http_filter: HttpFilterConfig { header_filter: None, path_filter: None, ports: PortList(Multiple([80, 8080])) }, listen_ports: {}, on_concurrent_steal: Abort }, outgoing: OutgoingConfig { tcp: true, udp: true, ignore_localhost: false, filter: None, unix_streams: None }, dns: true }, copy_target: CopyTargetConfig { enabled: false, scale_down: false } }, telemetry: true, kube_context: None, internal_proxy: InternalProxyConfig { start_idle_timeout: 60, idle_timeout: 5, log_level: None, log_destination: None }, use_proxy: true })}: mirrord_kube::api::container::job: Pod Phase = "Pending"
2024-04-04T10:42:58.113263Z DEBUG start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disa⠁ mirrord exec
    ✓ Running on latest (3.95.0)!
  ⠋ preparing to launch process
      ✓ layer extracted
      ✓ operator not found
      ✓ agent pod created
    ⠤ waiting for pod to be ready...                                                                                                                                                                               2024-04-04T10:43:00.786217Z DEBUG start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disa⠚ mirrord exec
    ✓ Running on latest (3.95.0)!
  ⠁ preparing to launch process
      ✓ layer extracted
      ✓ operator not found
      ✓ agent pod created
    ⠐ waiting for pod to be ready...                                                                                                                                                                               2024-04-04T10:43:01.060572Z TRACE start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disa⠒ mirrord exec
    ✓ Running on latest (3.95.0)!
  ⠁ preparing to launch process
      ✓ layer extracted
      ✓ operator not found
      ✓ agent pod created
      ✓ pod is ready                                                                                                                                                                                               2024-04-04T10:43:01.060977Z  INFO start:create_and_connect:create_agent{config=Some(LayerConfig { accept_invalid_certificates: false, skip_processes: None, skip_build_tools: true, pause: false, connect_tcp: None, operator: None, kubeconfig: None, sip_binaries: None, target: TargetConfig { path: None, namespace: None }, agent: AgentConfig { log_level: "info", namespace: None, image: AgentImageConfig("ghcr.io/metalbear-co/mirrord:3.95.0"), image_pull_policy: "IfNotPresent", image_pull_secrets: None, ttl: 1, ephemeral: false, communication_timeout: None, startup_timeout: 60, network_interface: None, flush_connections: true, disabled_capabilities: None, tolerations: None, resources: None, check_out_of_pods: true, privileged: false, nftables: false }, feature: FeatureConfig { env: EnvConfig { include: None, exclude: None, override: None, load_from_process: None, unset: None }, fs: FsConfig { mode: Read, read_write: None, read_only: None, local: None, not_found: None }, network: NetworkConfig { incoming: IncomingConfig { port_mapping: {}, ignore⠄ mirrord exec
    ✓ Running on latest (3.95.0)!
⠤ mirrord exec
    ✓ Running on latest (3.95.0)!
    ✓ ready to launch process
      ✓ layer extracted
      ✓ operator not found
      ✓ agent pod created
      ✓ pod is ready                                                                                                                                                                                               2024-04-04T10:43:01.815579Z TRACE ThreadId(01) new: mirrord_layer::file::filter: new fs_config=FsConfig { mode: LocalWithOverrides, read_write: None, read_only: None, local: None, not_found: None }
2024-04-04T10:43:01.827096Z TRACE ThreadId(01) new: mirrord_layer::file::filter: close time.busy=11.2ms time.idle=288µs fs_config=FsConfig { mode: LocalWithOverrides, read_write: None, read_only: None, local: None, not_found: None }
2024-04-04T10:43:01.827245Z TRACE ThreadId(01) enable_hooks: mirrord_layer: new enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.827997Z TRACE ThreadId(01) enable_hooks: mirrord_layer: hooked "close" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828046Z TRACE ThreadId(01) enable_hooks: mirrord_layer: hooked "__close_nocancel" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828063Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__close" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828076Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__close" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828091Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__close" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828102Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__close" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828114Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__close" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828123Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__close" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828135Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__close" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828144Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__close" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828156Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__close" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828166Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__close" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828179Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__close" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828189Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__close" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828218Z TRACE ThreadId(01) enable_hooks: mirrord_layer: hooked "fork" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828239Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "socket" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828260Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "recvfrom" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828281Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "sendto" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828300Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "recvmsg" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828321Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "sendmsg" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828343Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "bind" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828364Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "listen" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828385Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "connect" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828409Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "fcntl" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828430Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "dup" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828449Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "dup2" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828473Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "getpeername" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828504Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "getsockname" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828523Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "gethostname" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828557Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "accept4" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828579Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "dup3" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828600Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "accept" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828622Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "gethostbyname" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828648Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "getaddrinfo" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828668Z TRACE ThreadId(01) enable_hooks: mirrord_layer::socket::hooks: hooked "freeaddrinfo" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828695Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "open" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828711Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "open64" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828722Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "open64" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828733Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "open64" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828744Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "open64" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828755Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "open64" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828766Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "open64" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828777Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "open64" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828788Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "open64" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828808Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "open64" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828819Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "open64" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828829Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "open64" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828843Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "open64" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828865Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "openat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828880Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "openat64" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828890Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "openat64" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828900Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "openat64" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828909Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "openat64" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828920Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "openat64" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828929Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "openat64" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828940Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "openat64" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828950Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "openat64" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828962Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "openat64" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828972Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "openat64" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828982Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "openat64" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.828992Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "openat64" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829026Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "read" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829054Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "closedir" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829074Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "dirfd" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829100Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "pread" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829122Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "readv" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829144Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "preadv" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829166Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "lseek" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829187Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "write" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829208Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "pwrite" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829230Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "access" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829252Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "faccessat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829273Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "fsync" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829293Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "fdatasync" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829317Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "realpath" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829340Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "statx" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829360Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "__xstat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829375Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__xstat64" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829385Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__xstat64" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829397Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__xstat64" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829407Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__xstat64" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829419Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__xstat64" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829429Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__xstat64" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829440Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__xstat64" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829450Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__xstat64" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829461Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__xstat64" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829472Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__xstat64" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829485Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__xstat64" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829495Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__xstat64" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829514Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "__lxstat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829528Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__lxstat64" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829542Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__lxstat64" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829554Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__lxstat64" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829564Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__lxstat64" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829575Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__lxstat64" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829588Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__lxstat64" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829599Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__lxstat64" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829609Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__lxstat64" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829620Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__lxstat64" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829631Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__lxstat64" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829645Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "__lxstat64" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829655Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "__lxstat64" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829674Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "lstat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829698Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "fstat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829719Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "stat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829738Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "fstatat" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829763Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "fstatfs" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829788Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "fdopendir" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829806Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "readdir_r" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829821Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir64_r" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829832Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir64_r" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829843Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir64_r" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829853Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir64_r" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829865Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir64_r" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829876Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir64_r" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829887Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir64_r" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829897Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir64_r" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829908Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir64_r" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829919Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir64_r" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829930Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir64_r" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829940Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir64_r" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829959Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "readdir64" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829973Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir" in "libc.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829984Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir" in "libc.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.829999Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir" in "libpthread.so.0", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830009Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir" in "libpthread.so.0" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830020Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir" in "libgcc_s.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830031Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir" in "libgcc_s.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830042Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir" in "librt.so.1", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830052Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir" in "librt.so.1" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830063Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir" in "libm.so.6", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830074Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir" in "libm.so.6" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830085Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: found "readdir" in "libdl.so.2", hooking enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830095Z TRACE ThreadId(01) enable_hooks: mirrord_layer::hooks: hook "readdir" in "libdl.so.2" failed with err Function already replaced enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830114Z TRACE ThreadId(01) enable_hooks: mirrord_layer::file::hooks: hooked "opendir" enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830486Z TRACE ThreadId(01) enable_hooks: mirrord_layer: close time.busy=3.22ms time.idle=26.9µs enabled_file_ops=true enabled_remote_dns=true patch_binaries=[]
2024-04-04T10:43:01.830614Z  INFO ThreadId(01) mirrord_layer: Initializing mirrord-layer!
2024-04-04T10:43:01.830624Z TRACE ThreadId(01) mirrord_layer: Loaded into executable: /home/linuxbrew/.linuxbrew/Cellar/microsocks/1.0.4/bin/microsocks, on pid 2951133, with args: ["microsocks"]
2024-04-04T10:43:02.079391Z TRACE ThreadId(01) getaddrinfo: mirrord_layer::socket::ops: new rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 })
2024-04-04T10:43:02.079564Z TRACE ThreadId(01) getaddrinfo:remote_getaddrinfo: mirrord_layer::socket::ops: new rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 }) node="0.0.0.0"
2024-04-04T10:43:02.079668Z TRACE ThreadId(01) getaddrinfo:remote_getaddrinfo:make_request_with_response: mirrord_layer::proxy_connection: new rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 }) node="0.0.0.0" request=GetAddrInfoRequest { node: "0.0.0.0" }
2024-04-04T10:43:02.306285Z TRACE ThreadId(01) getaddrinfo:remote_getaddrinfo:make_request_with_response: mirrord_layer::proxy_connection: return=Ok(GetAddrInfoResponse(Ok(DnsLookup([LookupRecord { name: "", ip: 0.0.0.0 }])))) rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 }) node="0.0.0.0" request=GetAddrInfoRequest { node: "0.0.0.0" }
2024-04-04T10:43:02.306512Z TRACE ThreadId(01) getaddrinfo:remote_getaddrinfo:make_request_with_response: mirrord_layer::proxy_connection: close time.busy=227ms time.idle=76.8µs rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 }) node="0.0.0.0" request=GetAddrInfoRequest { node: "0.0.0.0" }
2024-04-04T10:43:02.306732Z TRACE ThreadId(01) getaddrinfo:remote_getaddrinfo: mirrord_layer::socket::ops: return=Ok([("", 0.0.0.0)]) rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 }) node="0.0.0.0"
2024-04-04T10:43:02.306810Z TRACE ThreadId(01) getaddrinfo:remote_getaddrinfo: mirrord_layer::socket::ops: close time.busy=227ms time.idle=60.9µs rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 }) node="0.0.0.0"
2024-04-04T10:43:02.306932Z TRACE ThreadId(01) getaddrinfo: mirrord_layer::socket::ops: getaddrinfo -> result 0x000055a2afeb2d00 rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 })
2024-04-04T10:43:02.306981Z TRACE ThreadId(01) getaddrinfo: mirrord_layer::socket::ops: return=Success(0x55a2afeb2d00) rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 })
2024-04-04T10:43:02.307032Z TRACE ThreadId(01) getaddrinfo: mirrord_layer::socket::ops: close time.busy=228ms time.idle=139µs rawish_node=Some("0.0.0.0") rawish_service=Some("1080") raw_hints=Some(addrinfo { ai_flags: 1, ai_family: 0, ai_socktype: 1, ai_protocol: 0, ai_addrlen: 0, ai_addr: 0x0, ai_canonname: 0x0, ai_next: 0x0 })
2024-04-04T10:43:02.307157Z TRACE ThreadId(01) socket: mirrord_layer::socket::ops: new domain=2 type_=1 protocol=0
2024-04-04T10:43:02.307325Z TRACE ThreadId(01) socket: mirrord_layer::socket::ops: return=Success(5) domain=2 type_=1 protocol=0
2024-04-04T10:43:02.307391Z TRACE ThreadId(01) socket: mirrord_layer::socket::ops: close time.busy=180µs time.idle=55.7µs domain=2 type_=1 protocol=0
2024-04-04T10:43:02.307492Z TRACE ThreadId(01) bind: mirrord_layer::socket::ops: new sockfd=5 address_length=16
2024-04-04T10:43:02.307606Z TRACE ThreadId(01) bind:bind_port: mirrord_layer::socket::ops: new sockfd=5 address_length=16 sockfd=5 domain=2 port=1080
2024-04-04T10:43:02.307667Z TRACE ThreadId(01) bind:bind_port: mirrord_layer::socket::ops: return=Success(()) sockfd=5 address_length=16 sockfd=5 domain=2 port=1080
2024-04-04T10:43:02.307723Z TRACE ThreadId(01) bind:bind_port: mirrord_layer::socket::ops: close time.busy=66.1µs time.idle=51.5µs sockfd=5 address_length=16 sockfd=5 domain=2 port=1080
2024-04-04T10:43:02.307796Z TRACE ThreadId(01) bind: mirrord_layer::socket::ops: return=Success(0) sockfd=5 address_length=16
2024-04-04T10:43:02.307846Z TRACE ThreadId(01) bind: mirrord_layer::socket::ops: close time.busy=310µs time.idle=46.6µs sockfd=5 address_length=16
2024-04-04T10:43:02.307930Z TRACE ThreadId(01) listen: mirrord_layer::socket::ops: new sockfd=5 backlog=4096
2024-04-04T10:43:02.307991Z  WARN ThreadId(01) listen: mirrord_layer::socket::ops: Listening while running targetless. A targetless agent is not exposed by any service. Therefore, letting this port bind happen locally instead of on the cluster. sockfd=5 backlog=4096
2024-04-04T10:43:02.308034Z TRACE ThreadId(01) listen: mirrord_layer::socket::ops: return=Bypass(BindWhenTargetless) sockfd=5 backlog=4096
2024-04-04T10:43:02.308083Z TRACE ThreadId(01) listen: mirrord_layer::socket::ops: close time.busy=107µs time.idle=46.2µs sockfd=5 backlog=4096
2024-04-04T10:43:09.765134Z TRACE ThreadId(01) accept: mirrord_layer::socket::ops: new sockfd=5 new_fd=6
2024-04-04T10:43:09.765187Z TRACE ThreadId(01) accept: mirrord_layer::socket::ops: return=Bypass(LocalFdNotFound(5)) sockfd=5 new_fd=6
2024-04-04T10:43:09.765210Z TRACE ThreadId(01) accept: mirrord_layer::socket::ops: close time.busy=29.1µs time.idle=53.7µs sockfd=5 new_fd=6

# At this moment I execute 
curl --socks5 127.0.0.1:1080 wep-back:3000/v1/health 
curl: (97) Could not resolve host: wep-back
FAIL

# At mirrord logs we see the crash and process stops
[1]    2951133 segmentation fault (core dumped)  RUST_LOG=mirrord=trace mirrord exec microsocks
FAIL

Your operating system and version

Ubuntu 22.04.3

Local process

microsocks

Local process version

1.0.1-2

Additional Info

No response

aviramha commented 7 months ago

Fixed in #2354