Husky Braitenberg Holodeck Experiment not loading


#1

Hi guys,

I’m trying to execute the aforementioned experiment after cloning it on a local instance of the NRP.

First of all: The “server status” is yellow. I’m just getting started here, so I don’t know what that means. Does this pose a problem?

Furthermore, when I click “Launch”, everything loads but then it gets stuck on this screen:

The wheel still spins but nothing happens… even after waiting for two hours.

Does anyone have any suggestions as to what might be the cause of this behavior and how to fix it?

Thanks in advance,

Tobi


#2

Hi Tobi,

a yellow server status indicates a low number of available servers but, in a local instance, that’s not an issue.

About the stuck asset loading screen, I would try as a quick workaround, to reload the page.
In case the problem persists, we could try to troubleshoot it analyzing the logs.

Cheers


#3

Hello,

Thank you for the quick reply.

Good to know that the yellow status is not a problem.

So the output log when just starting the server and not having opened it in the browser looks like this:

`nrpdevelopment@lenovo-ThinkPad-T560:~$ cle-kill
Found 0 NRP process groups to terminate.
nrpdevelopment@lenovo-ThinkPad-T560:~$ cle-nginx

  • Restarting nginx nginx [ OK ]
    nrpdevelopment@lenovo-ThinkPad-T560:~$ cle-start
    [1] 9483
    … logging to /home/nrpdevelopment/.ros/log/eeb591aa-5503-11e8-8eb9-34f39aeed79d/roslaunch-lenovo-ThinkPad-T560-9483.log
    Checking log directory for disk usage. This may take awhile.
    Press Ctrl-C to interrupt
    Done checking log file disk usage. Usage is <1GB.

started roslaunch server http://127.0.0.1:37803/
ros_comm version 1.12.13

SUMMARY

PARAMETERS

  • /rosdistro: kinetic
  • /rosversion: 1.12.13

NODES

auto-starting new master
process[master]: started with pid [9494]
ROS_MASTER_URI=http://127.0.0.1:11311/

setting /run_id to eeb591aa-5503-11e8-8eb9-34f39aeed79d
process[rosout-1]: started with pid [9507]
started core service [/rosout]
[2] 9524
[ INFO] [1526033602.325761636]: Waiting For connections on 0.0.0.0:8081
[3] 9553
the rosdep view is empty: call ‘sudo rosdep init’ and ‘rosdep update’
[INFO] [2018.5.11 12:13:23 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:226 @ Network::create_rngs_] : Creating default RNGs
[INFO] [2018.5.11 12:13:23 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:221 @ Network::create_rngs_] : Deleting existing random number generators
[INFO] [2018.5.11 12:13:23 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:226 @ Network::create_rngs_] : Creating default RNGs
[INFO] [2018.5.11 12:13:23 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:272 @ Network::create_grng_] : Creating new default global RNG

          -- N E S T --

Copyright © 2004 The NEST Initiative
Version 2.12.0 May 7 2018 09:08:45

This program is provided AS IS and comes with
NO WARRANTY. See the file LICENSE for details.

Problems or suggestions?
Visit http://www.nest-simulator.org

Type ‘nest.help()’ to find out more about NEST.
CSAConnector: libneurosim support not available in NEST.
Falling back on PyNN’s default CSAConnector.
Please re-compile NEST using --with-libneurosim=PATH
[4] 9563
[uWSGI] getting INI configuration from /home/nrpdevelopment/.local/etc/nginx/uwsgi-nrp.ini
*** Starting uWSGI 2.0.12-debian (64bit) on [Fri May 11 12:13:24 2018] ***
compiled with version: 5.4.0 20160609 on 31 August 2017 21:02:04
os: Linux-4.13.0-41-generic #46~16.04.1-Ubuntu SMP Thu May 3 10:06:43 UTC 2018
nodename: lenovo-ThinkPad-T560
machine: x86_64
clock source: unix
pcre jit disabled
detected number of CPU cores: 4
current working directory: /home/nrpdevelopment
detected binary path: /usr/bin/uwsgi-core
chdir() to /home/nrpdevelopment/Documents/NRP/ExDBackend/hbp_nrp_backend/
*** WARNING: you are running uWSGI without its master process manager ***
your processes number limit is 63059
your memory page size is 4096 bytes
detected max file descriptor number: 1024
lock engine: pthread robust mutexes
thunder lock: disabled (you can enable it with --thunder-lock)
uwsgi socket 0 bound to UNIX address /home/nrpdevelopment/.local/etc/nginx/nrp-services.sock fd 3
Python version: 2.7.12 (default, Dec 4 2017, 14:50:18) [GCC 5.4.0 20160609]
Set PythonHome to /home/nrpdevelopment/.opt/platform_venv
Python main interpreter initialized at 0x20a1970
python threads support enabled
your server socket listen backlog is limited to 100 connections
your mercy for graceful operations on workers is 60 seconds
mapped 144896 bytes (141 KB) for 8 cores
*** Operational MODE: threaded ***
2018-05-11 12:13:24,317 [MainThread ] [hbp_nrp_cles] [WARNING] Could not write to specified logfile or no logfile specified, logging to stdout now!
2018-05-11 12:13:24,594 [Thread-3 ] [rospy.intern] [INFO] topic[/rosout] adding connection to [/rosout], count 0
[INFO] [2018.5.11 12:13:24 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:226 @ Network::create_rngs_] : Creating default RNGs
[INFO] [2018.5.11 12:13:24 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:221 @ Network::create_rngs_] : Deleting existing random number generators
[INFO] [2018.5.11 12:13:24 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:226 @ Network::create_rngs_] : Creating default RNGs
[INFO] [2018.5.11 12:13:24 /home/nrpdevelopment/Documents/NRP/nest-simulator/nestkernel/rng_manager.cpp:272 @ Network::create_grng_] : Creating new default global RNG

          -- N E S T --

Copyright © 2004 The NEST Initiative
Version 2.12.0 May 7 2018 09:08:45

This program is provided AS IS and comes with
NO WARRANTY. See the file LICENSE for details.

Problems or suggestions?
Visit http://www.nest-simulator.org

Type ‘nest.help()’ to find out more about NEST.
CSAConnector: libneurosim support not available in NEST.
Falling back on PyNN’s default CSAConnector.
Please re-compile NEST using --with-libneurosim=PATH
[5] 9586
2018-05-11 12:13:25,501 [MainThread ] [hbp_nrp_back] [WARNING] Application started with uWSGI or any other framework. logging to console by default !
WSGI app 0 (mountpoint=’’) ready in 1 seconds on interpreter 0x20a1970 pid: 9565 (default app)
*** uWSGI is running in multiple interpreter mode ***
spawned uWSGI worker 1 (and the only) (pid: 9565, cores: 8)
registered capabilities (classes):

  • rosbridge_library.capabilities.call_service.CallService
  • rosbridge_library.capabilities.advertise.Advertise
  • rosbridge_library.capabilities.publish.Publish
  • rosbridge_library.capabilities.subscribe.Subscribe
  • <class ‘rosbridge_library.capabilities.defragmentation.Defragment’>
  • rosbridge_library.capabilities.advertise_service.AdvertiseService
  • rosbridge_library.capabilities.service_response.ServiceResponse
  • rosbridge_library.capabilities.unadvertise_service.UnadvertiseService
    2018-05-11 12:13:25 GMT+0200 Polling Backend Servers for Experiments, Health & Running Simulations every 5000 ms.
    [INFO] [1526033605.709602, 0.000000]: Rosbridge WebSocket server started on port 9090
    2018-05-11 12:13:25 GMT+0200 Listening on port: 8443
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/benchmark_p3dx/BenchmarkPioneer.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_husky_distributed_music/ExDDistributedBrainHuskyMUSIC.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_husky_distributed/ExDDistributedBrainHusky.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_husky_holodeck/ExDXMLExample_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_husky_sbc/ExDBraitenbergHuskySBC.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_husky_spinnaker/HuskySpinnaker.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_husky/ExDXMLExample.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_lauron_sbc/ExDBraitenbergLauronSBC.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_lauron/ExDBraitenbergLauron.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_mouse_holodeck_lab/ExDBraitenbergMouseHoloLab.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_mouse_lab/ExDBraitenbergMouseLab.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/braitenberg_mouse/ExDBraitenbergMouse.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/cdp1_mouse_holodeck/ExDMouseCDP1_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/cdp1_mouse_tactile/ExDMouseCDP1_tactile.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/cdp1_mouse/ExDMouseCDP1.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/demo_dvs_icub/ExDDvsIcub.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/demo_husky_sbc/ExDDemoHuskySBC.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/demo_manipulation/ExDDemoManipulation.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/demo_mouse_lab/ExDMouseLabExample.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/docked_mouse/ExDDockedMouse.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/dvs_robot_head/ExDDvsRobotHead.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/interactive_manipulation/ExDInteractiveManipulation.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/manipulation_force_holodeck/ExDManipulationForce_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/manipulation_force/ExDManipulationForce.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/manipulation_holodeck/ExDManipulation_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/manipulation_RL/ExDManipulationRL.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/manipulation/ExDManipulation.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/multiple_robots_tutorial/MultipleRobots.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/myoarm_nst/Myo_NST.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/myoarm_small/Myo_small.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/nao/ExDNao.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/neuronal_red_detection_holodeck/NeuronalRedDetection_Husky_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/neuronal_red_detection/NeuronalRedDetection_Husky.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/retina_icub/ExDRetinaICubTrackingRG.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/retina_mockup/ExDRetinaICubMockup.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/roboy/roboy.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/screen_switching_holodeck/ScreenSwitchingHuskyExperiment_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/screen_switching/ScreenSwitchingHuskyExperiment.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/spinnaker_example/ExDSpiNNakerExample.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_husky_timeout/ExDTemplateHuskyTimeout.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_husky/ExDTemplateHusky.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_icub_holodeck/ExDTemplateICubHolodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_icub/ExDTemplateICub.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_lauron_docked/ExDTemplateDockedLauron.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_lauron/ExDTemplateLauron.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_manipulation/ExDTemplateManipulation.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_mmm/ExDTemplateMMM.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_mouse/ExDTemplateMouseV2.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_new/TemplateNew.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/tigrillo_CPG/ExDTigrilloCPG.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/template_pioneer/ExDTemplatePioneer.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/tutorial_baseball_exercise/ExDTutorialBaseballExercise.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/tutorial_baseball_solution/ExDTutorialBaseballSolution.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/tutorial_tensorflow_husky/TensorFlowTutorial.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/user-avatar_test-environment/user-avatar_test-env.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/visual_tracking_icub_holodeck/ExDVisualTrackingICub_holodeck.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/visual_tracking_icub/ExDVisualTrackingICub.exc
    2018-05-11 12:13:25 GMT+0200 Parsing experiment file /home/nrpdevelopment/Documents/NRP/Experiments/zip_robot/ExDXMLExampleRobotZip.exc`

2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me/groups 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/experiments 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /availableServers 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_holodeck_0 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/benchmark_p3dx_0 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_0 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/benchmark_p3dx_0/experiment_configuration.exc?byname=true 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_0/experiment_configuration.exc?byname=true 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.jpg?byname=true 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/benchmark_p3dx_0/BenchmarkPioneer.jpg?byname=true 2018-05-11 12:17:54 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_0/ExDXMLExample.jpg?byname=true 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/experiments 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /availableServers 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_holodeck_0 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/benchmark_p3dx_0 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_0 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/benchmark_p3dx_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/experiments 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /availableServers 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_holodeck_0 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/benchmark_p3dx_0 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_0 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/benchmark_p3dx_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/experiments 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /availableServers 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_holodeck_0 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/benchmark_p3dx_0 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_0 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/benchmark_p3dx_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:24 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_0/experiment_configuration.exc?byname=true 2018-05-11 12:18:25,595 [Thread-6 ] [hbp_nrp_back] [INFO] Start cleanup

As you can see, the part after availableServers periodically repeats itself, and then eventually (shown in the last line here) there is an alert “Start cleanup”. There is a lot more of this periodical output after the one I posted, but nothing changes; it’s always the same text.


#4

(I had to split it up because of character limitation. Here is the rest of my post:)

When I click on “Launch” in the Holodeck Husky section, I get the following output:

`2018-05-11 12:21:59 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /server/localhost
2018-05-11 12:21:59 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me
[INFO] [1526034119.696282, 0.000000]: Client connected. 1 clients total.
2018-05-11 12:21:59 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0
2018-05-11 12:21:59 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.3ds?byname=true
2018-05-11 12:21:59 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.jpg?byname=true
the rosdep view is empty: call ‘sudo rosdep init’ and ‘rosdep update’
[INFO] [1526034120.016514, 0.000000]: [Client 0] Subscribed to /ros_cle_simulation/status
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.uis?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/bibi_configuration.bibi?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/brainvisualizer.json?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/braitenberg.py?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/csv_joint_state_monitor.py?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/csv_robot_position.py?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/csv_spike_monitor.py?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/model.sdf?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/robot.config?byname=true
2018-05-11 12:22:00 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/virtual_room.sdf?byname=true
2018-05-11 12:22:00,304 [uWSGIWorker1] [hbp_nrp_back] [INFO] Requesting simulation resources
2018-05-11 12:22:00,304 [uWSGIWorker1] [hbp_nrp_back] [INFO] simulation timeout initialized
2018-05-11 12:22:00,309 [Thread-7 ] [hbp_nrp_cles] [INFO] Create new simulation request
2018-05-11 12:22:00,310 [Thread-7 ] [hbp_nrp_cles] [INFO] No simulation running, starting a new simulation.
2018-05-11 12:22:00,311 [Thread-7 ] [hbp_nrp_cles] [INFO] Preparing new simulation with environment file: /tmp/nrpTemphIJAXi/virtual_room.sdf and ExD config file: /tmp/nrpTemphIJAXi/experiment_configuration.exc.
2018-05-11 12:22:00,311 [Thread-7 ] [hbp_nrp_cles] [INFO] Starting the experiment closed loop engine.
2018-05-11 12:22:00,311 [Thread-7 ] [hbp_nrp_cles] [INFO] Read XML Files
2018-05-11 12:22:00,367 [Thread-7 ] [hbp_nrp_cles] [INFO] Bibi: bibi_configuration.bibi
2018-05-11 12:22:00,368 [Thread-7 ] [hbp_nrp_cles] [INFO] BibiAbs:/tmp/nrpTemphIJAXi/bibi_configuration.bibi
2018-05-11 12:22:00,401 [Thread-7 ] [hbp_nrp_cles] [INFO] Path is /tmp/nrpTemphIJAXi/experiment_configuration.exc
2018-05-11 12:22:00,402 [Thread-7 ] [hbp_nrp_cles] [INFO] Setting up backend Notificator
2018-05-11 12:22:00,405 [Thread-7 ] [hbp_nrp_cles] [INFO] ROS notificator initialized
2018-05-11 12:22:00,405 [Thread-7 ] [hbp_nrp_cles] [INFO] Creating CLE Server
2018-05-11 12:22:00,406 [Thread-7 ] [hbp_nrp_cles] [WARNING] No RNG seed specified, generating a random value.
2018-05-11 12:22:00,406 [Thread-7 ] [hbp_nrp_cles] [INFO] RNG seed = 8623535599122824743
2018-05-11 12:22:00,406 [Thread-7 ] [hbp_nrp_cles] [INFO] Robot: model.sdf
2018-05-11 12:22:00,406 [Thread-7 ] [hbp_nrp_cles] [INFO] Looking for physicsEngine tag value in ExDConfig
2018-05-11 12:22:00,407 [Thread-7 ] [hbp_nrp_cles] [INFO] No physics engine specified explicitly. Using default setting ‘ode’
2018-05-11 12:22:00,407 [Thread-7 ] [hbp_nrp_cles] [INFO] gzserver arguments: --seed 8623535599122824743 -e ode /tmp/nrpTemphIJAXi/virtual_room.sdf
2018-05-11 12:22:00,407 [Thread-7 ] [hbp_nrp_cle.] [INFO] Starting gzserver
2018-05-11 12:22:00,458 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/status] adding connection to [/rosbridge_websocket], count 0
Found /home/nrpdevelopment/.local/bin/gzserver
Starting gzserver: OK.

[ INFO] [1526034122.872816880]: Finished loading Gazebo ROS API Plugin.
[ INFO] [1526034122.873079382]: waitForService: Service [/gazebo/set_physics_properties] has not been advertised, waiting…
[ INFO] [1526034122.885193774]: Gazebo ROS Recording Plugin initialized.
[ INFO] [1526034122.891985558]: Gazebo ROS Playback Plugin initialized.
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/experiments
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /availableServers
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_holodeck_0
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/benchmark_p3dx_0
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /joinableServers/braitenberg_husky_0
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/benchmark_p3dx_0/experiment_configuration.exc?byname=true
2018-05-11 12:22:04 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_0/experiment_configuration.exc?byname=true
2018-05-11 12:22:04,843 [Thread-7 ] [hbp_nrp_cle.] [INFO] Restarting Gazebo web client communication adapter
Now using node v0.10.48 (npm v2.15.1)
Found /home/nrpdevelopment/Documents/NRP/gzweb/gzbridge/ws_server.js
Starting node: OK.

2018-05-11 12:22:07,850 [Thread-7 ] [hbp_nrp_cles] [INFO] RobotAbs: /tmp/nrpTemphIJAXi/model.sdf
Pose message filter parameters:
minimum seconds between successive messages: 0.02
minimum XYZ distance squared between successive messages: 0.00001
minimum Quaternion distance squared between successive messages: 0.0001
Fri May 11 2018 12:22:08 GMT+0200 (CEST) Server is listening on port 7681
[ INFO] [1526034128.370509824]: Camera Plugin (robotNamespace = ), Info: the ‘robotNamespace’ param did not exit
[ INFO] [1526034128.378284839]: Camera Plugin (ns = ) <tf_prefix_>, set to “”
2018-05-11 12:22:08,452 [Thread-7 ] [hbp_nrp_cles] [INFO] Preparing CLE Server
2018-05-11 12:22:08,473 [Thread-7 ] [hbp_nrp_cle.] [INFO] Robot control adapter initialized
2018-05-11 12:22:08,493 [Thread-7 ] [hbp_nrp_cle.] [INFO] neuronal simulator initialized
2018-05-11 12:22:08,494 [Thread-7 ] [BrainLoader ] [INFO] Loading brain model from python: /tmp/nrpTemphIJAXi/braitenberg.py
2018-05-11 12:22:08,544 [Thread-7 ] [hbp_nrp_cle.] [INFO] Saving brain source
2018-05-11 12:22:08,545 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer functions node tfnode
2018-05-11 12:22:08,545 [Thread-7 ] [hbp_nrp_cle.] [INFO] PyNN communication adapter initialized
2018-05-11 12:22:08,545 [Thread-7 ] [hbp_nrp_cle.] [WARNING] ROS node already initialized with another name
2018-05-11 12:22:08,557 [Thread-7 ] [hbp_nrp_cles] [INFO] Registering ROS Service handlers
2018-05-11 12:22:08,567 [Thread-13 ] [rospy.intern] [INFO] topic[/clock] adding connection to [http://127.0.0.1:44461/], count 0
2018-05-11 12:22:08,576 [Thread-7 ] [hbp_nrp_cles] [INFO] Registering ROS Service handlers
2018-05-11 12:22:08,583 [Thread-15 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/0/lifecycle] adding connection to [http://127.0.0.1:43235/], count 0
2018-05-11 12:22:08,585 [Thread-14 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/0/lifecycle] adding connection to [http://127.0.0.1:43357/], count 1
2018-05-11 12:22:08,603 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/0/lifecycle] adding connection to [/ros_cle_simulation_9555_1526033604188], count 0
2018-05-11 12:22:08,630 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function csv_spike_monitor transfers to robot /monitor/spike_recorder : SpikeEvent using [-inf, <hbp_nrp_cle.tf_framework._CSVRecorder.MapCSVRecorder object at 0x7f49dc199710>, <hbp_nrp_cle.tf_framework._Neuron2Robot.MapSpikeSink object at 0x7f49dc199850>]
2018-05-11 12:22:08,631 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS publisher created: topic name = /monitor/spike_recorder, topic type = <class ‘cle_ros_msgs.msg._SpikeEvent.SpikeEvent’>
2018-05-11 12:22:08,633 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.ISpikeRecorder’>” requested (device)
2018-05-11 12:22:08,641 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function csv_joint_state_monitor transfers to neurons [-inf, <hbp_nrp_cle.tf_framework._Robot2Neuron.MapRobotSubscriber object at 0x7f49dc1bf590>, <hbp_nrp_cle.tf_framework._CSVRecorder.MapCSVRecorder object at 0x7f49dc1bf910>]
2018-05-11 12:22:08,645 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS subscriber created: topic name = /joint_states, topic type = <class ‘sensor_msgs.msg._JointState.JointState’>
2018-05-11 12:22:08,657 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function csv_robot_position transfers to neurons [-inf, <hbp_nrp_cle.tf_framework._Robot2Neuron.MapRobotSubscriber object at 0x7f49dc18e610>, <hbp_nrp_cle.tf_framework._CSVRecorder.MapCSVRecorder object at 0x7f49dc186b10>, <hbp_nrp_cle.tf_framework._GlobalData.MapVariable object at 0x7f49dc1bf990>]
2018-05-11 12:22:08,659 [Thread-18 ] [rospy.intern] [INFO] topic[/joint_states] adding connection to [http://127.0.0.1:44461/], count 0
2018-05-11 12:22:08,661 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS subscriber created: topic name = /gazebo/model_states, topic type = <class ‘gazebo_msgs.msg._ModelStates.ModelStates’>
2018-05-11 12:22:08,664 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function all_neurons_spike_monitor monitors (root).record[slice(0, 8, 1)]
2018-05-11 12:22:08,678 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS publisher created: topic name = /monitor/spike_recorder, topic type = <class ‘cle_ros_msgs.msg._SpikeEvent.SpikeEvent’>
2018-05-11 12:22:08,678 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.ISpikeRecorder’>” requested (device)
2018-05-11 12:22:08,683 [Thread-19 ] [rospy.intern] [INFO] topic[/gazebo/model_states] adding connection to [http://127.0.0.1:44461/], count 0
2018-05-11 12:22:08,686 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function left_wheel_neuron_rate_monitor monitors (root).actors[1]
2018-05-11 12:22:08,686 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS publisher created: topic name = /monitor/population_rate, topic type = <class ‘cle_ros_msgs.msg._SpikeRate.SpikeRate’>
2018-05-11 12:22:08,697 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/0/lifecycle] adding connection to [/nrp_backend], count 1
2018-05-11 12:22:08,713 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.IPopulationRate’>” requested (device)
2018-05-11 12:22:08,721 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function linear_twist transfers to robot /husky/cmd_vel : Twist using [-inf, <hbp_nrp_cle.tf_framework._Neuron2Robot.MapSpikeSink object at 0x7f49dc130550>, <hbp_nrp_cle.tf_framework._Neuron2Robot.MapSpikeSink object at 0x7f49dc130750>]
2018-05-11 12:22:08,721 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS publisher created: topic name = /husky/cmd_vel, topic type = <class ‘geometry_msgs.msg._Twist.Twist’>
2018-05-11 12:22:08,727 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.ILeakyIntegratorAlpha’>” requested (device)
2018-05-11 12:22:08,733 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.ILeakyIntegratorAlpha’>” requested (device)
2018-05-11 12:22:08,743 [Thread-7 ] [hbp_nrp_cle.] [INFO] Initialize transfer function eye_sensor_transmit transfers to neurons [-inf, <hbp_nrp_cle.tf_framework._Robot2Neuron.MapRobotSubscriber object at 0x7f49dc130e50>, <hbp_nrp_cle.tf_framework._Neuron2Robot.MapSpikeSource object at 0x7f49dc0d1d90>, <hbp_nrp_cle.tf_framework._Neuron2Robot.MapSpikeSource object at 0x7f49dc0d1a90>, <hbp_nrp_cle.tf_framework._Neuron2Robot.MapSpikeSource object at 0x7f49dc0d1a50>]
2018-05-11 12:22:08,748 [Thread-7 ] [hbp_nrp_cle.] [INFO] ROS subscriber created: topic name = /husky/camera, topic type = <class ‘sensor_msgs.msg._Image.Image’>
2018-05-11 12:22:08,751 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.IPoissonSpikeGenerator’>” requested (device)
2018-05-11 12:22:08,755 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.IPoissonSpikeGenerator’>” requested (device)
2018-05-11 12:22:08,759 [Thread-7 ] [hbp_nrp_cle.] [INFO] Communication object with type “<class ‘hbp_nrp_cle.brainsim.BrainInterface.IPoissonSpikeGenerator’>” requested (device)
2018-05-11 12:22:08,771 [Thread-23 ] [rospy.intern] [INFO] topic[/husky/camera] adding connection to [http://127.0.0.1:44461/], count 0
2018-05-11 12:22:08,872 [Thread-3 ] [rospy.intern] [INFO] topic[/husky/cmd_vel] adding connection to [/gazebo], count 0
2018-05-11 12:22:09,780 [Thread-7 ] [hbp_nrp_cles] [INFO] CLELauncher Finished.
2018-05-11 12:22:09,780 [Thread-7 ] [hbp_nrp_cles] [INFO] Initialization done
2018-05-11 12:22:09,780 [Thread-7 ] [hbp_nrp_cles] [INFO] Spawning new thread that will manage the experiment execution.
2018-05-11 12:22:09,781 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/reset
2018-05-11 12:22:09,782 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/extend_timeout
2018-05-11 12:22:09,784 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/get_transfer_functions
2018-05-11 12:22:09,785 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/add_transfer_function
2018-05-11 12:22:09,787 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/activate_transfer_function
2018-05-11 12:22:09,789 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/edit_transfer_function
2018-05-11 12:22:09,794 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/get_structured_transfer_functions
2018-05-11 12:22:09,796 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/set_structured_transfer_function
2018-05-11 12:22:09,798 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/delete_transfer_function
2018-05-11 12:22:09,801 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/get_brain
2018-05-11 12:22:09,803 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/set_brain
2018-05-11 12:22:09,806 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/get_populations
2018-05-11 12:22:09,822 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/get_CSV_recorders_files
2018-05-11 12:22:09,826 [uWSGIWorker1] [hbp_nrp_back] [INFO] Connecting to ROS service /ros_cle_simulation/0/simulation_recorder
2018-05-11 12:22:09,833 [uWSGIWorker1] [hbp_nrp_back] [INFO] simulation initialized
2018-05-11 12:22:09,834 [/ros_cle_sim] [transitions.] [INFO] Initiating transition from state created to state paused…
2018-05-11 12:22:09,835 [/ros_cle_sim] [transitions.] [INFO] Executing callback ‘initialize’ before transition.
2018-05-11 12:22:09,835 [/ros_cle_sim] [transitions.] [INFO] Exited state created
2018-05-11 12:22:09,835 [/ros_cle_sim] [transitions.] [INFO] Entered state paused
2018-05-11 12:22:09,835 [/ros_cle_sim] [transitions.] [INFO] Executed callback ‘<transitions.extensions.locking.LockedMethod instance at 0x7f49dc1899e0>’ after transition.
2018-05-11 12:22:10 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0
2018-05-11 12:22:10 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true
2018-05-11 12:22:11 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/default-owner
[INFO] [1526034131.037118, 0.001000]: [Client 0] Subscribed to /ros_cle_simulation/logs
2018-05-11 12:22:11,066 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/logs] adding connection to [/rosbridge_websocket], count 0
[ INFO] [1526034131.248623955]: Handling Request: /
[INFO] [1526034131.328819, 0.001000]: [Client 0] Subscribed to /ros_cle_simulation/cle_error
[INFO] [1526034131.344337, 0.001000]: [Client 0] Subscribed to /ros_cle_simulation/cle_error
2018-05-11 12:22:11,407 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/cle_error] adding connection to [/rosbridge_websocket], count 0
[INFO] [1526034131.501913, 0.001000]: [Client 0] Subscribed to /ros_cle_simulation/cle_error
2018-05-11 12:22:11 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.uis?byname=true
[ INFO] [1526034131.726030606]: Handling Request: /
[INFO] [1526034131.752979, 0.001000]: [Client 0] Subscribed to /ros_cle_simulation/status
Fri May 11 2018 12:22:12 GMT+0200 (CEST) Connection accepted.
2018-05-11 12:22:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.3ds?byname=true
2018-05-11 12:22:14 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.3ds?byname=true
2018-05-11 12:22:21 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:21 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:31 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:31 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:41 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:41 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:51 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:22:51 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:23:01 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true
2018-05-11 12:23:01 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true`

The last couple lines also repeat themselves and there is no other, new output (besides this one 2018-05-11 12:23:25,694 [Thread-6 ] [hbp_nrp_back] [INFO] Start cleanup after like 2 minutes). This is the stage where the spinning wheel is shown and nothing happens.

When I refresh the page on the spinning wheel, some progress bars fill up and disappear and after that the spinning wheel is shown again and the page gets stuck in the same place. Here is the output when refreshing the page:

Fri May 11 2018 12:24:38 GMT+0200 (CEST) Peer 127.0.0.1 disconnected. [INFO] [1526034278.113493, 0.001000]: Client disconnected. 0 clients total. 2018-05-11 12:24:38,349 [Thread-2 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/logs] removing connection to /rosbridge_websocket 2018-05-11 12:24:38,350 [Thread-2 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/cle_error] removing connection to /rosbridge_websocket 2018-05-11 12:24:38,350 [Thread-2 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/status] removing connection to /rosbridge_websocket 2018-05-11 12:24:38 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /server/localhost 2018-05-11 12:24:38 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me 2018-05-11 12:24:38 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me/groups 2018-05-11 12:24:38 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0 2018-05-11 12:24:38 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/experiment_configuration.exc?byname=true 2018-05-11 12:24:38 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/default-owner [INFO] [1526034278.767052, 0.001000]: Client connected. 1 clients total. [INFO] [1526034278.805612, 0.001000]: [Client 1] Subscribed to /ros_cle_simulation/logs 2018-05-11 12:24:38,816 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/logs] adding connection to [/rosbridge_websocket], count 0 [ INFO] [1526034278.956635039]: Handling Request: / [INFO] [1526034279.036196, 0.001000]: [Client 1] Subscribed to /ros_cle_simulation/cle_error [INFO] [1526034279.042885, 0.001000]: [Client 1] Subscribed to /ros_cle_simulation/cle_error 2018-05-11 12:24:39,090 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/cle_error] adding connection to [/rosbridge_websocket], count 0 [INFO] [1526034279.140082, 0.001000]: [Client 1] Subscribed to /ros_cle_simulation/cle_error [ INFO] [1526034279.328995627]: Handling Request: / [INFO] [1526034279.493114, 0.001000]: [Client 1] Subscribed to /ros_cle_simulation/status 2018-05-11 12:24:39,534 [Thread-3 ] [rospy.intern] [INFO] topic[/ros_cle_simulation/status] adding connection to [/rosbridge_websocket], count 0 Fri May 11 2018 12:24:39 GMT+0200 (CEST) Connection accepted. 2018-05-11 12:24:39 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.uis?byname=true 2018-05-11 12:24:41 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /identity/me 2018-05-11 12:24:41 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.3ds?byname=true 2018-05-11 12:24:41 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/ExDXMLExample.3ds?byname=true 2018-05-11 12:24:48 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true 2018-05-11 12:24:48 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true 2018-05-11 12:24:58 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true 2018-05-11 12:24:58 GMT+0200 [REQUEST from ::ffff:127.0.0.1] GET /storage/braitenberg_husky_holodeck_0/edit.lock?byname=true

Thanks a lot in advance for any help!


#5

2018-05-11 12:22:09,835 [/ros_cle_sim] [transitions.] [INFO] Entered state paused

The log looks ok, the simulation has been launched and is in paused state.

Could you also post the frontend log?
You can find it in the console tab of your browser’s developers tool (Ctrl+Shift+I in Chrome).


#6

OK good to know.

The frontend log looks like this:







Looks like there might be some problems if I’m not mistaken :smiley:


#7

Quite a few :smile:

Some textures are definitely missing.
Those textures are generated during the update process.
Could you check if $HBP/Models/husky_model/meshes contains some jpg files with a name beginning with PBR_?

In case there aren’t any, it means they haven’t been generated in the first place.
You can force the process executing the following commands in a shell:

  cd $HBP
  virtualenv lower_res
  source lower_res/bin/activate
  pip install pillow==4.3.0
  python $HBP/user-scripts/generatelowrespbr.py
  deactivate
  rm -rf lower_res

#8

So I listed the jpg files starting with PBR and there were a few. Then I executed the steps you described and afterwards there were more files with that description listed. After that, the platform worked! Thank you so much!

Do you have an idea why this didn’t work in the first place and how we can prevent new users from encountering the same problem?


#9

I’m happy that the issue has been solved.

Ultimately, it looks like the installation of the Models went wrong during the first setup.

Sadly, without a log of the process, it’s hard to tell exactly what happened.


#10

The command

  python $HBP/user-scripts/generatelowrespbr.py

is one of the last steps of the installation script. Thus almost any failure in the installation script, or an abortion, will result in missing textures.