telepresence

telepresence.log

   0.0 TEL | Telepresence 0.95 launched at Mon Dec 17 15:56:32 2018
   0.0 TEL |   /usr/bin/telepresence
   0.0 TEL | Platform: linux
   0.0 TEL | Python 3.6.7 (default, Oct 22 2018, 11:32:17)
   0.0 TEL | [GCC 8.2.0]
   0.0 TEL | [1] Running: uname -a
   0.0   1 | Linux azurite 4.18.0-12-generic #13-Ubuntu SMP Wed Nov 14 15:17:05 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux
   0.0 TEL | BEGIN SPAN main.py:47(main)
   0.0 TEL | BEGIN SPAN startup.py:55(__init__)
   0.0 TEL | [2] Capturing: kubectl version --short
   0.4 TEL | [3] Capturing: kubectl config current-context
   0.6 TEL | [4] Capturing: kubectl config view -o json
   0.9 TEL | [5] Capturing: kubectl --context development/ocp-pre-******-com:443/user get ns development
   1.4 TEL | Command: oc 1.11.0+d4cacc0
   1.4 TEL | Context: development/ocp-pre-******-com:443/user, namespace: development, version: 1.9.1+a0ce1bc657
   1.4 TEL | [6] Capturing: minishift ip
   1.6 TEL | END SPAN startup.py:55(__init__)    1.6s
   1.6 TEL | [7] Capturing: ssh -V
   1.6 TEL | [8] Running: sudo -n echo -n
   1.6 >>> | Starting proxy with method 'vpn-tcp', which has the following limitations: All processes are affected, only one telepresence can run per machine, and you can't use other VPNs. You may need to add cloud hosts and headless services with --also-proxy. For a full list of method limitations see https://telepresence.io/reference/methods.html
   1.6 >>> | Volumes are rooted at $TELEPRESENCE_ROOT. See https://telepresence.io/howto/volumes.html for details.
   1.6 TEL | [9] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pods telepresence-connectivity-check --ignore-not-found
   2.5 TEL | Scout info: {'latest_version': '0.96', 'application': 'telepresence', 'notices': []}
   2.5 TEL | BEGIN SPAN deployment.py:44(create_new_deployment)
   2.5 TEL | [10] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development delete --ignore-not-found svc,deploy --selector=telepresence=d359f14a9de64b58bba58f751a355923
   2.9 TEL | [11] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development run --restart=Always --limits=cpu=100m,memory=256Mi --requests=cpu=25m,memory=64Mi telepresence-1545058592-2670083-21817 --image=datawire/telepresence-k8s:0.95 --labels=telepresence=d359f14a9de64b58bba58f751a355923
   3.2 TEL | END SPAN deployment.py:44(create_new_deployment)    0.8s
   3.2 TEL | BEGIN SPAN remote.py:154(get_remote_info)
   3.2 TEL | BEGIN SPAN remote.py:78(get_deployment_json)
   3.2 TEL | [12] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get deploymentconfig -o json --export --selector=telepresence=d359f14a9de64b58bba58f751a355923
   3.6 TEL | END SPAN remote.py:78(get_deployment_json)    0.4s
   3.6 TEL | Searching for Telepresence pod:
   3.6 TEL |   with name telepresence-1545058592-2670083-21817-*
   3.6 TEL |   with labels {'telepresence': 'd359f14a9de64b58bba58f751a355923'}
   3.6 TEL | [13] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod -o json --export --selector=telepresence=d359f14a9de64b58bba58f751a355923
   5.0 TEL | [14] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod -o json --export --selector=telepresence=d359f14a9de64b58bba58f751a355923
   6.5 TEL | [15] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod -o json --export --selector=telepresence=d359f14a9de64b58bba58f751a355923
   8.0 TEL | [16] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod -o json --export --selector=telepresence=d359f14a9de64b58bba58f751a355923
   9.4 TEL | [17] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod -o json --export --selector=telepresence=d359f14a9de64b58bba58f751a355923
   9.8 TEL | Checking telepresence-1545058592-2670083-21817-1-77bmc
   9.8 TEL | Looks like we've found our pod!
   9.8 TEL | BEGIN SPAN remote.py:116(wait_for_pod)
   9.8 TEL | [18] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  10.4 TEL | [19] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  11.1 TEL | [20] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  11.7 TEL | [21] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  12.4 TEL | [22] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  13.0 TEL | [23] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  13.6 TEL | [24] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  14.2 TEL | [25] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  14.8 TEL | [26] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development get pod telepresence-1545058592-2670083-21817-1-77bmc -o json
  15.2 TEL | END SPAN remote.py:116(wait_for_pod)    5.4s
  15.2 TEL | END SPAN remote.py:154(get_remote_info)   12.0s
  15.2 TEL | BEGIN SPAN connect.py:36(connect)
  15.2 TEL | [27] Launching kubectl logs: oc --context development/ocp-pre-******-com:443/user --namespace development logs -f telepresence-1545058592-2670083-21817-1-77bmc --container telepresence-1545058592-2670083-21817 --tail=10
  15.2 TEL | [28] Launching kubectl port-forward: oc --context development/ocp-pre-******-com:443/user --namespace development port-forward telepresence-1545058592-2670083-21817-1-77bmc 41739:8022
  15.2 TEL | [29] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  15.2 TEL | [29] exit 255 in 0.02 secs.
  15.5 TEL | [30] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  15.5 TEL | [30] exit 255 in 0.01 secs.
  15.7 TEL | [31] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  15.7 TEL | [31] exit 255 in 0.01 secs.
  16.0 TEL | [32] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  16.0 TEL | [32] exit 255 in 0.02 secs.
  16.1  28 | Forwarding from 127.0.0.1:41739 -> 8022
  16.1  28 | Forwarding from [::1]:41739 -> 8022
  16.3 TEL | [33] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  16.3  28 | Handling connection for 41739
  17.5  33 | Connection to localhost closed by remote host.
  17.5 TEL | [33] exit 255 in 1.19 secs.
  17.7 TEL | [34] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  17.7  28 | Handling connection for 41739
  19.2  34 | Connection to localhost closed by remote host.
  19.2 TEL | [34] exit 255 in 1.47 secs.
  19.4 TEL | [35] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  19.4  28 | Handling connection for 41739
  20.5  35 | Connection to localhost closed by remote host.
  20.5 TEL | [35] exit 255 in 1.06 secs.
  20.7 TEL | [36] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  20.7  28 | Handling connection for 41739
  21.7  36 | Connection to localhost closed by remote host.
  21.7 TEL | [36] exit 255 in 0.95 secs.
  21.9 TEL | [37] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  22.0  28 | Handling connection for 41739
  23.3  37 | Connection to localhost closed by remote host.
  23.3 TEL | [37] exit 255 in 1.35 secs.
  23.5 TEL | [38] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  23.6  28 | Handling connection for 41739
  24.1  27 | 2018-12-17T14:56:53+0000 [-] Loading ./forwarder.py...
  24.1  27 | 2018-12-17T14:56:56+0000 [-] /etc/resolv.conf changed, reparsing
  24.1  27 | 2018-12-17T14:56:56+0000 [-] Resolver added ('10.148.204.71', 53) to server list
  24.1  27 | 2018-12-17T14:56:56+0000 [-] SOCKSv5Factory starting on 9050
  24.1  27 | 2018-12-17T14:56:56+0000 [socks.SOCKSv5Factory#info] Starting factory <socks.SOCKSv5Factory object at 0x7f87c32a0080>
  24.1  27 | 2018-12-17T14:56:56+0000 [-] DNSDatagramProtocol starting on 9053
  24.1  27 | 2018-12-17T14:56:56+0000 [-] Starting protocol <twisted.names.dns.DNSDatagramProtocol object at 0x7f87c32a0400>
  24.1  27 | 2018-12-17T14:56:56+0000 [-] Loaded.
  24.1  27 | 2018-12-17T14:56:56+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] twistd 18.9.0 (/usr/bin/python3.6 3.6.5) starting up.
  24.1  27 | 2018-12-17T14:56:56+0000 [twisted.scripts._twistd_unix.UnixAppLogger#info] reactor class: twisted.internet.epollreactor.EPollReactor.
  24.5  38 | Connection to localhost closed by remote host.
  24.5 TEL | [38] exit 255 in 0.92 secs.
  24.7 TEL | [39] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  24.7  28 | Handling connection for 41739
  25.5  39 | Connection to localhost closed by remote host.
  25.5 TEL | [39] exit 255 in 0.79 secs.
  25.8 TEL | [40] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  25.8  28 | Handling connection for 41739
  26.6  40 | Connection to localhost closed by remote host.
  26.6 TEL | [40] exit 255 in 0.81 secs.
  26.8 TEL | [41] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  26.8  28 | Handling connection for 41739
  27.8  41 | Connection to localhost closed by remote host.
  27.8 TEL | [41] exit 255 in 0.99 secs.
  28.1 TEL | [42] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  28.1  28 | Handling connection for 41739
  29.0  42 | Connection to localhost closed by remote host.
  29.0 TEL | [42] exit 255 in 0.94 secs.
  29.3 TEL | [43] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  29.3  28 | Handling connection for 41739
  30.1  43 | Connection to localhost closed by remote host.
  30.1 TEL | [43] exit 255 in 0.80 secs.
  30.3 TEL | [44] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  30.3  28 | Handling connection for 41739
  31.1  44 | Connection to localhost closed by remote host.
  31.1 TEL | [44] exit 255 in 0.80 secs.
  31.4 TEL | [45] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  31.4  28 | Handling connection for 41739
  31.6 TEL | [46] Running: sudo -n echo -n
  32.3  45 | Connection to localhost closed by remote host.
  32.3 TEL | [45] exit 255 in 0.91 secs.
  32.5 TEL | [47] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  32.5  28 | Handling connection for 41739
  33.5  47 | Connection to localhost closed by remote host.
  33.5 TEL | [47] exit 255 in 0.94 secs.
  33.7 TEL | [48] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  33.7  28 | Handling connection for 41739
  34.5  48 | Connection to localhost closed by remote host.
  34.5 TEL | [48] exit 255 in 0.80 secs.
  34.8 TEL | [49] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  34.8  28 | Handling connection for 41739
  35.6  49 | Connection to localhost closed by remote host.
  35.6 TEL | [49] exit 255 in 0.80 secs.
  35.8 TEL | [50] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  35.8  28 | Handling connection for 41739
  36.7  50 | Connection to localhost closed by remote host.
  36.7 TEL | [50] exit 255 in 0.85 secs.
  36.9 TEL | [51] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  36.9  28 | Handling connection for 41739
  37.8  51 | Connection to localhost closed by remote host.
  37.8 TEL | [51] exit 255 in 0.86 secs.
  38.0 TEL | [52] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  38.0  28 | Handling connection for 41739
  38.9  52 | Connection to localhost closed by remote host.
  38.9 TEL | [52] exit 255 in 0.91 secs.
  39.2 TEL | [53] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  39.2  28 | Handling connection for 41739
  40.0  53 | Connection to localhost closed by remote host.
  40.0 TEL | [53] exit 255 in 0.78 secs.
  40.2 TEL | [54] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  40.2  28 | Handling connection for 41739
  41.0  54 | Connection to localhost closed by remote host.
  41.0 TEL | [54] exit 255 in 0.79 secs.
  41.3 TEL | [55] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  41.3  28 | Handling connection for 41739
  42.1  55 | Connection to localhost closed by remote host.
  42.1 TEL | [55] exit 255 in 0.81 secs.
  42.3 TEL | [56] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  42.3  28 | Handling connection for 41739
  43.2  56 | Connection to localhost closed by remote host.
  43.2 TEL | [56] exit 255 in 0.85 secs.
  43.4 TEL | [57] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  43.4  28 | Handling connection for 41739
  44.3  57 | Connection to localhost closed by remote host.
  44.3 TEL | [57] exit 255 in 0.91 secs.
  44.6 TEL | [58] Running: ssh -F /dev/null -q -oStrictHostKeyChecking=no -oUserKnownHostsFile=/dev/null -p 41739 telepresence@localhost /bin/true
  44.6  28 | Handling connection for 41739
  45.4  58 | Connection to localhost closed by remote host.
  45.4 TEL | [58] exit 255 in 0.80 secs.
  45.4 TEL | CRASH: SSH isn't starting.
  45.4 TEL | Traceback (most recent call last):
  45.4 TEL |   File "/usr/bin/telepresence/telepresence/cli.py", line 130, in crash_reporting
  45.4 TEL |     yield
  45.4 TEL |   File "/usr/bin/telepresence/telepresence/main.py", line 70, in main
  45.4 TEL |     socks_port, ssh = do_connect(runner, remote_info)
  45.4 TEL |   File "/usr/bin/telepresence/telepresence/connect/connect.py", line 99, in do_connect
  45.4 TEL |     return connect(runner_, remote_info, is_container_mode, args.expose)
  45.4 TEL |   File "/usr/bin/telepresence/telepresence/connect/connect.py", line 57, in connect
  45.4 TEL |     ssh.wait()
  45.4 TEL |   File "/usr/bin/telepresence/telepresence/connect/ssh.py", line 82, in wait
  45.4 TEL |     raise RuntimeError("SSH isn't starting.")
  45.4 TEL | RuntimeError: SSH isn't starting.
  45.4 TEL | (calling crash reporter...)
  48.3 >>> | Exit cleanup in progress
  48.3 TEL | (Cleanup) Kill BG process [28] kubectl port-forward
  48.3 TEL | [28] exit -15
  48.3 TEL | (Cleanup) Kill BG process [27] kubectl logs
  48.3 TEL | [27] exit -15
  48.3 TEL | (Cleanup) Delete new deployment
  48.3 TEL | [59] Capturing: oc --context development/ocp-pre-******-com:443/user --namespace development delete --ignore-not-found svc,deploy --selector=telepresence=d359f14a9de64b58bba58f751a355923
  48.8 TEL | (Cleanup) Show version notice
  48.8 >>> | 
  48.8 >>> | Telepresence 0.96 is available (you're running 0.95). https://www.telepresence.io/reference/changelog
  48.8 TEL | (Cleanup) Kill sudo privileges holder
  48.8 TEL | (Cleanup) Stop time tracking
  48.8 TEL | END SPAN main.py:47(main)   48.8s
  48.8 TEL | (Cleanup) Remove temporary directory
  48.8 TEL | (Cleanup) Save caches
  49.7 TEL | (sudo privileges holder thread exiting)