URGENT - DAQ error on first run - Timeout expired

[pisuke]

Hi @grafnu @spectre-storm and @henry54809.
There is a bug that has been introduced recently that prevents DAQ from starting any test.
It appears since the first time DAQ is run and it also persists in switch configuration mode (tested both on Cisco and AT switches).
See below the run time cli log.
The same error has been experienced by Forest Rock.
The error is shown as this:

...
ERROR:host:Monitoring timeout for ipaddr after 120s
ERROR:host:Target port 1 monitor error: Timeout expired
...
ERROR:runner:Target port 1 active True exception: Timeout expired
...

Could you please have a look? Thanks.

Full log below.

Activating venv
No local/system.conf found, copying defaults from misc/system_base.conf...
Loading config from local/system.conf into inst/config/system.conf
Starting Tue 31 Mar 14:12:08 BST 2020
Clearing previous state...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
Release version 1.0.1
ovsdb-server is running with pid 413
ovs-vswitchd is running with pid 480
Creating mudacl templates...
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.1/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 690ms
2 actionable tasks: 2 up-to-date
Running mudacl regression test...
Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/acl_templates
Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/port_acls
Compare out/acl_templates/ with setup/acl_templates/...
Compare out/port_acls/ with setup/port_acls/...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.1/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 703ms
2 actionable tasks: 2 up-to-date

Executing mudacl generator on mud_files/...
Writing output files to /home/francesco/Code/daq/pisuke/daq/inst/acl_templates
inst/acl_templates:
total 40
-rw-r--r-- 1 root root  540 Mar 31 14:12 template_bacnet_acl.yaml
-rw-r--r-- 1 root root  332 Mar 31 14:12 template_bacnet_frdev_acl.yaml
-rw-r--r-- 1 root root  332 Mar 31 14:12 template_bacnet_todev_acl.yaml
-rw-r--r-- 1 root root  768 Mar 31 14:12 template_baseline_acl.yaml
-rw-r--r-- 1 root root 1966 Mar 31 14:12 template_commissioning_acl.yaml
-rw-r--r-- 1 root root 1452 Mar 31 14:12 template_controller_acl.yaml
-rw-r--r-- 1 root root   78 Mar 31 14:12 template_default_acl.yaml
-rw-r--r-- 1 root root 1442 Mar 31 14:12 template_lightbulb_acl.yaml
-rw-r--r-- 1 root root  258 Mar 31 14:12 template_raw_acl.yaml
-rw-r--r-- 1 root root  496 Mar 31 14:12 template_telnet_acl.yaml

inst/port_acls:
total 0
No external switch configured.
Sourcing misc/startup_base.cmd...
DAQ autostart cmd/faux
Activating venv
Loading config from local/system.conf into inst/config/system.conf
Launching faux ...
Clensing old container daq-faux
DAQ autoclean docker kill daq-faux
Removing old interface faux
Adding new interface to 15229...
Done with faux device launch.
Entering virtual python environment...
Using python3 at /home/francesco/Code/daq/pisuke/daq/venv/bin/python3
Executing: python3 -u daq/daq.py /home/francesco/Code/daq/pisuke/daq/local/system.conf -s
Prepending /home/francesco/Code/daq/pisuke/daq/binhack to PATH
processing arg: /home/francesco/Code/daq/pisuke/daq/local/system.conf
Reading config from /home/francesco/Code/daq/pisuke/daq/local/system.conf
Reading config from misc/system.conf
processing arg: -s
base_conf=misc/module_config.json
dhcp_lease_time=500s
initial_dhcp_lease_time=120s
intf_names=faux
long_dhcp_response_sec=80
run_mode=local
single_shot=True
site_description="Baseline Configuration"
site_path=local/site/
startup_cmds=misc/startup_base.cmd
INFO:daq:pid is 15317
INFO:gcp:No gcp_cred credential specified in config
INFO:config:Loading config from misc/module_config.json
INFO:config:Skipping missing local/site/module_config.json
INFO:runner:Reading test definition file misc/host_tests.conf
INFO:runner:Reading test definition file subset/pentests/build.conf
INFO:runner:Configured with tests ['pass', 'fail', 'ping', 'bacnet', 'mudgee', 'nmap', 'discover']
INFO:network:Activating faucet topology...
INFO:topology:No device_specs file specified, skipping...
INFO:topology:Writing network config to inst/faucet.yaml
INFO:topology:Starting faucet...
INFO:network:Creating ovs sec with dpid/port 2/7
INFO:network:Added switch link pri-eth1 <-> sec-eth7
INFO:network:Attaching device interface faux on port 1.
INFO:network:Attaching device interface sec-2 on port 2.
INFO:network:Attaching device interface sec-3 on port 3.
INFO:network:Attaching device interface sec-4 on port 4.
INFO:network:Attaching device interface sec-5 on port 5.
INFO:network:Attaching device interface sec-6 on port 6.
INFO:network:Starting mininet...
INFO:mininet:*** Configuring hosts
INFO:mininet:*** Starting controller
INFO:mininet:controller
INFO:mininet:*** Starting 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:...
INFO:runner:Waiting for system to settle...
INFO:runner:Entering main event loop.
INFO:runner:See docs/troubleshooting.md if this blocks for more than a few minutes.
INFO:runner:System port 7 on dpid 2 is active True
INFO:runner:Port 1 dpid 2 is now active True
INFO:runner:Port 1 dpid 2 learned 9a:02:57:1e:8f:00
INFO:runner:Gateway for device group 9a02571e8f00 not found, initializing base 1...
INFO:gateway:Initializing gateway 9a02571e8f00 as gw01/10
INFO:gateway:Added networking host gw01 on port 10 at 10.20.0.1
INFO:gateway:Added dummy target dummy01 on port 11 at 10.20.0.2
INFO:gateway:Gateway 1 startup capture gw01-eth0 in container's /tmp/gateway.pcap
INFO:dhcp:DHCP monitor gw01 waiting for replies...
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping dummy01->gw01
INFO:runner:Test ping dummy01->192.168.84.1
INFO:runner:Test ping gw01->dummy01 from 192.168.84.1
INFO:gateway:Attaching target 1 to gateway group 9a02571e8f00
WARNING:host:Creating new device dir: /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00
WARNING:host:Skipping defaults since no device_template found
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:host:Device config reloaded: True 9a:02:57:1e:8f:00
INFO:config:Writing config to /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/aux/module_config.json
INFO:host:Host 1 running with enabled tests ['pass', 'fail', 'ping', 'nmap']
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T131304+0000.md
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T131304+0000.pdf
INFO:runner:Target port 1 registered 9a:02:57:1e:8f:00
INFO:host:Target port 1 initializing...
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:network:Creating mirror pair mirror-1 <-> mirror-1-ext at 1001
INFO:host:Target port 1 startup pcap capture
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: True
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 waiting for ip as 9a:02:57:1e:8f:00
INFO:host:Target port 1 using normal DHCP mode
ERROR:host:Monitoring timeout for ipaddr after 120s
ERROR:host:Target port 1 monitor error: Timeout expired
INFO:host:Target port 1 monitor scan complete
ERROR:runner:Target port 1 active True exception: Timeout expired
INFO:gateway:Detach target 1 from gateway group 9a02571e8f00
INFO:runner:Retiring target gateway 1, 9a:02:57:1e:8f:00, 9a02571e8f00, 1
INFO:gateway:Terminating gateway 1/9a02571e8f00
INFO:runner:Target port 1 finalize: ['01:ipaddr:TimeoutError'] (Timeout expired)
INFO:runner:Target port 1 cancel 9a:02:57:1e:8f:00 (#1/0).
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: False
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 terminate, running unknown, trigger False: _target_set_cancel
INFO:network:Deleting mirror pair mirror-1 <-> mirror-1-ext
WARNING:runner:Suppressing future tests because test done in single shot.
INFO:runner:Remaining target sets: []
INFO:stream:Monitoring 0 fds 
WARNING:runner:No active ports remaining (0 monitors), ending test run.
INFO:runner:Stopping network...
INFO:mininet:*** Stopping 1 controllers
INFO:mininet:controller
INFO:mininet:*** Stopping 1 links
INFO:mininet:.
INFO:mininet:*** Stopping 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:*** Stopping 1 hosts
INFO:mininet:gw01
INFO:mininet:*** Done
INFO:runner:Done with runner.
INFO:daq:DAQ runner returned 0
Cleanup docker kill daq-faux
daq-faux
Done with run, exit 0

[henry54809]

@pisuke, Hey would you please sync with master and retry? Your 120s timeout indicates that your branch is a couple commits behind.

[grafnu]

Your run is also showing version 1.0.1 -- when if it's up-to-date it should be something like:

Release version release-1.0.1-145-g8890ab9

What does "git log -n 1" show?

[pisuke]

I have synced the repo again, and here is the output of git log -n 1

root@arup-daq-lab:~/Code/daq/pisuke/daq# git log -1
commit 92b6150ff8bc9a4adcdf457060297c61cf178e17 (HEAD -> master, origin/master, origin/HEAD)
Merge: 7d201a9 d3d3ede
Author: Francesco Anselmo <francesco.anselmo@gmail.com>
Date:   Tue Mar 31 19:33:11 2020 +0100

    Merge branch 'master' of https://github.com/pisuke/daq

It looks in sync with the latest commit to me.

I've re-built DAQ and here is a new cli log. Still not looking good unfortunately.

root@arup-daq-lab:~/Code/daq/pisuke/daq# cmd/run -s

Activating venv
Loading config from local/system.conf into inst/config/system.conf
Starting Tue 31 Mar 21:50:30 BST 2020
Clearing previous state...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
HEAD is now at f13396aa Merge pull request #3476 from mab68/orderedacl1
HEAD is now at b03e4666 Built docker images (#57)
Release version release-1.0.1-159-g92b6150
ovsdb-server is running with pid 413
ovs-vswitchd is running with pid 480
Creating mudacl templates...
Starting a Gradle Daemon, 1 incompatible Daemon could not be reused, use --status for details
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 6s
2 actionable tasks: 2 up-to-date
Running mudacl regression test...
Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/acl_templates
Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/port_acls
Compare out/acl_templates/ with setup/acl_templates/...
Compare out/port_acls/ with setup/port_acls/...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 775ms
2 actionable tasks: 2 up-to-date

Executing mudacl generator on mud_files/...
Writing output files to /home/francesco/Code/daq/pisuke/daq/inst/acl_templates
inst/acl_templates:
total 40
-rw-r--r-- 1 root root  540 Mar 31 21:50 template_bacnet_acl.yaml
-rw-r--r-- 1 root root  332 Mar 31 21:50 template_bacnet_frdev_acl.yaml
-rw-r--r-- 1 root root  332 Mar 31 21:50 template_bacnet_todev_acl.yaml
-rw-r--r-- 1 root root  768 Mar 31 21:50 template_baseline_acl.yaml
-rw-r--r-- 1 root root 1966 Mar 31 21:50 template_commissioning_acl.yaml
-rw-r--r-- 1 root root 1452 Mar 31 21:50 template_controller_acl.yaml
-rw-r--r-- 1 root root   78 Mar 31 21:50 template_default_acl.yaml
-rw-r--r-- 1 root root 1442 Mar 31 21:50 template_lightbulb_acl.yaml
-rw-r--r-- 1 root root  258 Mar 31 21:50 template_raw_acl.yaml
-rw-r--r-- 1 root root  496 Mar 31 21:50 template_telnet_acl.yaml

inst/port_acls:
total 0
No external switch configured.
Sourcing misc/startup_base.cmd...
DAQ autostart cmd/faux
Activating venv
Loading config from local/system.conf into inst/config/system.conf
Launching faux ...
DAQ autoclean docker kill daq-faux
Removing old interface faux
Adding new interface to 14649...
Done with faux device launch.
Entering virtual python environment...
Using python3 at /home/francesco/Code/daq/pisuke/daq/venv/bin/python3
Executing: python3 -u daq/daq.py /home/francesco/Code/daq/pisuke/daq/local/system.conf -s
Prepending /home/francesco/Code/daq/pisuke/daq/binhack to PATH
processing arg: /home/francesco/Code/daq/pisuke/daq/local/system.conf
Reading config from /home/francesco/Code/daq/pisuke/daq/local/system.conf
Reading config from misc/system.conf
processing arg: -s
base_conf=misc/module_config.json
dhcp_lease_time=500s
initial_dhcp_lease_time=120s
intf_names=faux
long_dhcp_response_sec=105
run_mode=local
single_shot=True
site_description="Baseline Configuration"
site_path=local/site/
startup_cmds=misc/startup_base.cmd
INFO:daq:pid is 14750
INFO:gcp:No gcp_cred credential specified in config
INFO:config:Loading config from misc/module_config.json
INFO:config:Skipping missing local/site/module_config.json
INFO:topology:No device_specs file specified, skipping...
INFO:runner:Reading test definition file misc/host_tests.conf
INFO:runner:Reading test definition file subset/pentests/build.conf
INFO:runner:Configured with tests ['pass', 'fail', 'ping', 'bacnet', 'mudgee', 'nmap', 'discover']
INFO:network:Activating faucet topology...
INFO:topology:Starting faucet...
INFO:network:Initializing faucitizer...
INFO:network:Creating ovs sec with dpid/port 2/7
INFO:network:Added switch link pri-eth1 <-> sec-eth7
INFO:network:Attaching device interface faux on port 1.
INFO:network:Attaching device interface sec-2 on port 2.
INFO:network:Attaching device interface sec-3 on port 3.
INFO:network:Attaching device interface sec-4 on port 4.
INFO:network:Attaching device interface sec-5 on port 5.
INFO:network:Attaching device interface sec-6 on port 6.
INFO:network:Starting mininet...
INFO:mininet:*** Configuring hosts
INFO:mininet:*** Starting controller
INFO:mininet:controller
INFO:mininet:*** Starting 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:...
INFO:runner:Waiting for system to settle...
INFO:runner:Entering main event loop.
INFO:runner:See docs/troubleshooting.md if this blocks for more than a few minutes.
INFO:runner:System port 7 on dpid 2 is active True
INFO:runner:Port 1 dpid 2 is now active True
INFO:runner:Port 1 dpid 2 learned 9a:02:57:1e:8f:00
INFO:runner:Gateway for device group 9a02571e8f00 not found, initializing base 1...
INFO:gateway:Initializing gateway 9a02571e8f00 as gw01/10
INFO:gateway:Added networking host gw01 on port 10 at 10.20.0.1
INFO:gateway:Added dummy target dummy01 on port 11 at 10.20.0.2
INFO:gateway:Gateway 1 startup capture gw01-eth0 in container's /tmp/gateway.pcap
INFO:dhcp:DHCP monitor gw01 waiting for replies...
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping dummy01->gw01
INFO:runner:Test ping dummy01->192.168.84.1
INFO:runner:Test ping gw01->dummy01 from 192.168.84.1
INFO:gateway:Attaching target 1 to gateway group 9a02571e8f00
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:host:Device config reloaded: True 9a:02:57:1e:8f:00
INFO:config:Writing config to /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/aux/module_config.json
INFO:host:Host 1 running with enabled tests ['pass', 'fail', 'ping', 'nmap']
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T205122+0000.md
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T205122+0000.pdf
INFO:runner:Target port 1 registered 9a:02:57:1e:8f:00
INFO:host:Target port 1 initializing...
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:network:Creating mirror pair mirror-1 <-> mirror-1-ext at 1001
INFO:host:Target port 1 startup pcap capture
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: True
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 waiting for ip as 9a:02:57:1e:8f:00
INFO:host:Target port 1 using normal DHCP mode
ERROR:host:Monitoring timeout for ipaddr after 300s
ERROR:host:Target port 1 monitor error: Timeout expired
INFO:host:Target port 1 monitor scan complete
ERROR:runner:Target port 1 active True exception: Timeout expired
INFO:gateway:Detach target 1 from gateway group 9a02571e8f00
INFO:runner:Retiring target gateway 1, 9a:02:57:1e:8f:00, 9a02571e8f00, 1
INFO:gateway:Terminating gateway 1/9a02571e8f00
INFO:runner:Target port 1 finalize: ['01:ipaddr:TimeoutError'] (Timeout expired)
INFO:runner:Target port 1 cancel 9a:02:57:1e:8f:00 (#1/0).
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: False
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 terminate, running unknown, trigger False: _target_set_cancel
INFO:network:Deleting mirror pair mirror-1 <-> mirror-1-ext
WARNING:runner:Suppressing future tests because test done in single shot.
INFO:runner:Remaining target sets: []
INFO:stream:Monitoring 0 fds 
WARNING:runner:No active ports remaining (0 monitors), ending test run.
INFO:runner:Stopping network...
INFO:mininet:*** Stopping 1 controllers
INFO:mininet:controller
INFO:mininet:*** Stopping 1 links
INFO:mininet:.
INFO:mininet:*** Stopping 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:*** Stopping 1 hosts
INFO:mininet:gw01
INFO:mininet:*** Done
INFO:runner:Done with runner.
INFO:daq:DAQ runner returned 0
Cleanup docker kill daq-faux
daq-faux
Done with run, exit 0

[henry54809]

@pisuke Did you also rebuilt all the docker images? The daqf/networking image needs to be updated for the new networking script fo take effect.

[pisuke]

@henry54809 yes I did a cmd/clean and then bin/clean_dev, bin/setup_dev and cmd/build.

I've just now done a fresh clone from faucetsnd/daq, cleaned all the docker images, done a fresh build.
This is my git log -n 1

root@arup-daq-lab:~/Code/daq/faucetsdn/daq# git log -n 1
commit 7d201a9103653dacc2b41510314cf5a698a7a369 (HEAD -> master, origin/master, origin/HEAD)
Author: henry54809 <haolidu@google.com>
Date:   Tue Mar 31 09:42:26 2020 -0700

    glinux setup script (#330)

And another run log that shows the same error:

root@arup-daq-lab:~/Code/daq/faucetsdn/daq# cmd/run -s
Activating venv
Loading config from local/system.conf into inst/config/system.conf
Starting Tue 31 Mar 22:29:23 BST 2020
Clearing previous state...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
HEAD is now at f13396aa Merge pull request #3476 from mab68/orderedacl1
HEAD is now at b03e4666 Built docker images (#57)
Release version release-1.0.1-146-g7d201a9
ovsdb-server is running with pid 413
ovs-vswitchd is running with pid 480
Creating mudacl templates...
> Task :compileJava
> Task :processResources NO-SOURCE
> Task :classes
> Task :shadowJar

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 4s
2 actionable tasks: 2 executed
Running mudacl regression test...
Writing output files to /home/francesco/Code/daq/faucetsdn/daq/mudacl/out/acl_templates
Writing output files to /home/francesco/Code/daq/faucetsdn/daq/mudacl/out/port_acls
Compare out/acl_templates/ with setup/acl_templates/...
Compare out/port_acls/ with setup/port_acls/...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 724ms
2 actionable tasks: 2 up-to-date

Executing mudacl generator on mud_files/...
Writing output files to /home/francesco/Code/daq/faucetsdn/daq/inst/acl_templates
inst/acl_templates:
total 40
-rw-r--r-- 1 root root  540 Mar 31 22:29 template_bacnet_acl.yaml
-rw-r--r-- 1 root root  332 Mar 31 22:29 template_bacnet_frdev_acl.yaml
-rw-r--r-- 1 root root  332 Mar 31 22:29 template_bacnet_todev_acl.yaml
-rw-r--r-- 1 root root  768 Mar 31 22:29 template_baseline_acl.yaml
-rw-r--r-- 1 root root 1966 Mar 31 22:29 template_commissioning_acl.yaml
-rw-r--r-- 1 root root 1452 Mar 31 22:29 template_controller_acl.yaml
-rw-r--r-- 1 root root   78 Mar 31 22:29 template_default_acl.yaml
-rw-r--r-- 1 root root 1442 Mar 31 22:29 template_lightbulb_acl.yaml
-rw-r--r-- 1 root root  258 Mar 31 22:29 template_raw_acl.yaml
-rw-r--r-- 1 root root  496 Mar 31 22:29 template_telnet_acl.yaml

inst/port_acls:
total 0
No external switch configured.
Sourcing misc/startup_base.cmd...
DAQ autostart cmd/faux
Activating venv
Loading config from local/system.conf into inst/config/system.conf
Launching faux ...
DAQ autoclean docker kill daq-faux
Removing old interface faux
Adding new interface to 23922...
Done with faux device launch.
Entering virtual python environment...
Using python3 at /home/francesco/Code/daq/faucetsdn/daq/venv/bin/python3
Executing: python3 -u daq/daq.py /home/francesco/Code/daq/faucetsdn/daq/local/system.conf -s
Prepending /home/francesco/Code/daq/faucetsdn/daq/binhack to PATH
processing arg: /home/francesco/Code/daq/faucetsdn/daq/local/system.conf
Reading config from /home/francesco/Code/daq/faucetsdn/daq/local/system.conf
Reading config from misc/system.conf
processing arg: -s
base_conf=misc/module_config.json
dhcp_lease_time=500s
initial_dhcp_lease_time=120s
intf_names=faux
long_dhcp_response_sec=105
run_mode=local
single_shot=True
site_description="Baseline Configuration"
site_path=local/site/
startup_cmds=misc/startup_base.cmd
INFO:daq:pid is 24021
INFO:gcp:No gcp_cred credential specified in config
INFO:config:Loading config from misc/module_config.json
INFO:config:Skipping missing local/site/module_config.json
INFO:topology:No device_specs file specified, skipping...
INFO:runner:Reading test definition file misc/host_tests.conf
INFO:runner:Reading test definition file subset/pentests/build.conf
INFO:runner:Configured with tests ['pass', 'fail', 'ping', 'bacnet', 'mudgee', 'nmap', 'discover']
INFO:network:Activating faucet topology...
INFO:topology:Starting faucet...
INFO:network:Initializing faucitizer...
INFO:network:Creating ovs sec with dpid/port 2/7
INFO:network:Added switch link pri-eth1 <-> sec-eth7
INFO:network:Attaching device interface faux on port 1.
INFO:network:Attaching device interface sec-2 on port 2.
INFO:network:Attaching device interface sec-3 on port 3.
INFO:network:Attaching device interface sec-4 on port 4.
INFO:network:Attaching device interface sec-5 on port 5.
INFO:network:Attaching device interface sec-6 on port 6.
INFO:network:Starting mininet...
INFO:mininet:*** Configuring hosts
INFO:mininet:*** Starting controller
INFO:mininet:controller
INFO:mininet:*** Starting 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:...
INFO:runner:Waiting for system to settle...
INFO:runner:Entering main event loop.
INFO:runner:See docs/troubleshooting.md if this blocks for more than a few minutes.
INFO:runner:System port 7 on dpid 2 is active True
INFO:runner:Port 1 dpid 2 is now active True
INFO:runner:Port 1 dpid 2 learned 9a:02:57:1e:8f:00
INFO:runner:Gateway for device group 9a02571e8f00 not found, initializing base 1...
INFO:gateway:Initializing gateway 9a02571e8f00 as gw01/10
INFO:gateway:Added networking host gw01 on port 10 at 10.20.0.1
INFO:gateway:Added dummy target dummy01 on port 11 at 10.20.0.2
INFO:gateway:Gateway 1 startup capture gw01-eth0 in container's /tmp/gateway.pcap
INFO:dhcp:DHCP monitor gw01 waiting for replies...
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping dummy01->gw01
INFO:runner:Test ping dummy01->192.168.84.1
INFO:runner:Test ping gw01->dummy01 from 192.168.84.1
INFO:gateway:Attaching target 1 to gateway group 9a02571e8f00
WARNING:host:Creating new device dir: /home/francesco/Code/daq/faucetsdn/daq/local/site/mac_addrs/9a02571e8f00
WARNING:host:Skipping defaults since no device_template found
INFO:config:Skipping missing /home/francesco/Code/daq/faucetsdn/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:config:Skipping missing /home/francesco/Code/daq/faucetsdn/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:host:Device config reloaded: True 9a:02:57:1e:8f:00
INFO:config:Writing config to /home/francesco/Code/daq/faucetsdn/daq/local/site/mac_addrs/9a02571e8f00/aux/module_config.json
INFO:host:Host 1 running with enabled tests ['pass', 'fail', 'ping', 'nmap']
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T213007+0000.md
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T213007+0000.pdf
INFO:runner:Target port 1 registered 9a:02:57:1e:8f:00
INFO:host:Target port 1 initializing...
INFO:config:Skipping missing /home/francesco/Code/daq/faucetsdn/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:network:Creating mirror pair mirror-1 <-> mirror-1-ext at 1001
INFO:host:Target port 1 startup pcap capture
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: True
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 waiting for ip as 9a:02:57:1e:8f:00
INFO:host:Target port 1 using normal DHCP mode
ERROR:host:Monitoring timeout for ipaddr after 300s
ERROR:host:Target port 1 monitor error: Timeout expired
INFO:host:Target port 1 monitor scan complete
ERROR:runner:Target port 1 active True exception: Timeout expired
INFO:gateway:Detach target 1 from gateway group 9a02571e8f00
INFO:runner:Retiring target gateway 1, 9a:02:57:1e:8f:00, 9a02571e8f00, 1
INFO:gateway:Terminating gateway 1/9a02571e8f00
INFO:runner:Target port 1 finalize: ['01:ipaddr:TimeoutError'] (Timeout expired)
INFO:runner:Target port 1 cancel 9a:02:57:1e:8f:00 (#1/0).
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: False
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 terminate, running unknown, trigger False: _target_set_cancel
INFO:network:Deleting mirror pair mirror-1 <-> mirror-1-ext
WARNING:runner:Suppressing future tests because test done in single shot.
INFO:runner:Remaining target sets: []
INFO:stream:Monitoring 0 fds 
WARNING:runner:No active ports remaining (0 monitors), ending test run.
INFO:runner:Stopping network...
INFO:mininet:*** Stopping 1 controllers
INFO:mininet:controller
INFO:mininet:*** Stopping 1 links
INFO:mininet:.
INFO:mininet:*** Stopping 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:*** Stopping 1 hosts
INFO:mininet:gw01
INFO:mininet:*** Done
INFO:runner:Done with runner.
INFO:daq:DAQ runner returned 0
Cleanup docker kill daq-faux
daq-faux
Done with run, exit 0

[pisuke]

@henry54809 for reference:

root@arup-daq-lab:~/Code/daq/faucetsdn/daq# cat build/docker_build.networking 
docker build -t daqf/networking -f docker/Dockerfile.networking .
Sending build context to Docker daemon  169.6MB
Step 1/5 : FROM daqf/aardvark:latest
 ---> 65bf89968558
Step 2/5 : RUN $AG update && $AG install dnsmasq ethtool iptables netcat python freeradius
 ---> Running in b6a6db6fb7ec
debconf: delaying package configuration, since apt-utils is not installed
Selecting previously unselected package multiarch-support.
(Reading database ... 6916 files and directories currently installed.)
Preparing to unpack .../multiarch-support_2.27-3ubuntu1_amd64.deb ...
Unpacking multiarch-support (2.27-3ubuntu1) ...
Setting up multiarch-support (2.27-3ubuntu1) ...
Selecting previously unselected package libnfnetlink0:amd64.
(Reading database ... 6919 files and directories currently installed.)
Preparing to unpack .../0-libnfnetlink0_1.0.1-3_amd64.deb ...
Unpacking libnfnetlink0:amd64 (1.0.1-3) ...
Selecting previously unselected package libbsd0:amd64.
Preparing to unpack .../1-libbsd0_0.8.7-1ubuntu0.1_amd64.deb ...
Unpacking libbsd0:amd64 (0.8.7-1ubuntu0.1) ...
Selecting previously unselected package libwbclient0:amd64.
Preparing to unpack .../2-libwbclient0_2%3a4.7.6+dfsg~ubuntu-0ubuntu2.15_amd64.deb ...
Unpacking libwbclient0:amd64 (2:4.7.6+dfsg~ubuntu-0ubuntu2.15) ...
Selecting previously unselected package perl-modules-5.26.
Preparing to unpack .../3-perl-modules-5.26_5.26.1-6ubuntu0.3_all.deb ...
Unpacking perl-modules-5.26 (5.26.1-6ubuntu0.3) ...
Selecting previously unselected package libpython2.7-minimal:amd64.
Preparing to unpack .../4-libpython2.7-minimal_2.7.17-1~18.04_amd64.deb ...
Unpacking libpython2.7-minimal:amd64 (2.7.17-1~18.04) ...
Selecting previously unselected package python2.7-minimal.
Preparing to unpack .../5-python2.7-minimal_2.7.17-1~18.04_amd64.deb ...
Unpacking python2.7-minimal (2.7.17-1~18.04) ...
Selecting previously unselected package python-minimal.
Preparing to unpack .../6-python-minimal_2.7.15~rc1-1_amd64.deb ...
Unpacking python-minimal (2.7.15~rc1-1) ...
Selecting previously unselected package libpython2.7-stdlib:amd64.
Preparing to unpack .../7-libpython2.7-stdlib_2.7.17-1~18.04_amd64.deb ...
Unpacking libpython2.7-stdlib:amd64 (2.7.17-1~18.04) ...
Selecting previously unselected package python2.7.
Preparing to unpack .../8-python2.7_2.7.17-1~18.04_amd64.deb ...
Unpacking python2.7 (2.7.17-1~18.04) ...
Selecting previously unselected package libpython-stdlib:amd64.
Preparing to unpack .../9-libpython-stdlib_2.7.15~rc1-1_amd64.deb ...
Unpacking libpython-stdlib:amd64 (2.7.15~rc1-1) ...
Setting up libpython2.7-minimal:amd64 (2.7.17-1~18.04) ...
Setting up python2.7-minimal (2.7.17-1~18.04) ...
Linking and byte-compiling packages for runtime python2.7...
Setting up python-minimal (2.7.15~rc1-1) ...
Selecting previously unselected package python.
(Reading database ... 9030 files and directories currently installed.)
Preparing to unpack .../00-python_2.7.15~rc1-1_amd64.deb ...
Unpacking python (2.7.15~rc1-1) ...
Selecting previously unselected package openssl.
Preparing to unpack .../01-openssl_1.1.1-1ubuntu2.1~18.04.5_amd64.deb ...
Unpacking openssl (1.1.1-1ubuntu2.1~18.04.5) ...
Selecting previously unselected package ca-certificates.
Preparing to unpack .../02-ca-certificates_20180409_all.deb ...
Unpacking ca-certificates (20180409) ...
Selecting previously unselected package libdbus-1-3:amd64.
Preparing to unpack .../03-libdbus-1-3_1.12.2-1ubuntu1.1_amd64.deb ...
Unpacking libdbus-1-3:amd64 (1.12.2-1ubuntu1.1) ...
Selecting previously unselected package libip4tc0:amd64.
Preparing to unpack .../04-libip4tc0_1.6.1-2ubuntu2_amd64.deb ...
Unpacking libip4tc0:amd64 (1.6.1-2ubuntu2) ...
Selecting previously unselected package libxtables12:amd64.
Preparing to unpack .../05-libxtables12_1.6.1-2ubuntu2_amd64.deb ...
Unpacking libxtables12:amd64 (1.6.1-2ubuntu2) ...
Selecting previously unselected package netbase.
Preparing to unpack .../06-netbase_5.4_all.deb ...
Unpacking netbase (5.4) ...
Selecting previously unselected package libip6tc0:amd64.
Preparing to unpack .../07-libip6tc0_1.6.1-2ubuntu2_amd64.deb ...
Unpacking libip6tc0:amd64 (1.6.1-2ubuntu2) ...
Selecting previously unselected package libiptc0:amd64.
Preparing to unpack .../08-libiptc0_1.6.1-2ubuntu2_amd64.deb ...
Unpacking libiptc0:amd64 (1.6.1-2ubuntu2) ...
Selecting previously unselected package libnetfilter-conntrack3:amd64.
Preparing to unpack .../09-libnetfilter-conntrack3_1.0.6-2_amd64.deb ...
Unpacking libnetfilter-conntrack3:amd64 (1.0.6-2) ...
Selecting previously unselected package iptables.
Preparing to unpack .../10-iptables_1.6.1-2ubuntu2_amd64.deb ...
Unpacking iptables (1.6.1-2ubuntu2) ...
Selecting previously unselected package libgdbm5:amd64.
Preparing to unpack .../11-libgdbm5_1.14.1-6_amd64.deb ...
Unpacking libgdbm5:amd64 (1.14.1-6) ...
Selecting previously unselected package libkrb5support0:amd64.
Preparing to unpack .../12-libkrb5support0_1.16-2ubuntu0.1_amd64.deb ...
Unpacking libkrb5support0:amd64 (1.16-2ubuntu0.1) ...
Selecting previously unselected package libk5crypto3:amd64.
Preparing to unpack .../13-libk5crypto3_1.16-2ubuntu0.1_amd64.deb ...
Unpacking libk5crypto3:amd64 (1.16-2ubuntu0.1) ...
Selecting previously unselected package libkeyutils1:amd64.
Preparing to unpack .../14-libkeyutils1_1.5.9-9.2ubuntu2_amd64.deb ...
Unpacking libkeyutils1:amd64 (1.5.9-9.2ubuntu2) ...
Selecting previously unselected package libkrb5-3:amd64.
Preparing to unpack .../15-libkrb5-3_1.16-2ubuntu0.1_amd64.deb ...
Unpacking libkrb5-3:amd64 (1.16-2ubuntu0.1) ...
Selecting previously unselected package libgssapi-krb5-2:amd64.
Preparing to unpack .../16-libgssapi-krb5-2_1.16-2ubuntu0.1_amd64.deb ...
Unpacking libgssapi-krb5-2:amd64 (1.16-2ubuntu0.1) ...
Selecting previously unselected package dnsmasq-base.
Preparing to unpack .../17-dnsmasq-base_2.79-1_amd64.deb ...
Unpacking dnsmasq-base (2.79-1) ...
Selecting previously unselected package dnsmasq.
Preparing to unpack .../18-dnsmasq_2.79-1_all.deb ...
Unpacking dnsmasq (2.79-1) ...
Selecting previously unselected package ethtool.
Preparing to unpack .../19-ethtool_1%3a4.15-0ubuntu1_amd64.deb ...
Unpacking ethtool (1:4.15-0ubuntu1) ...
Selecting previously unselected package freetds-common.
Preparing to unpack .../20-freetds-common_1.00.82-2ubuntu0.1_all.deb ...
Unpacking freetds-common (1.00.82-2ubuntu0.1) ...
Selecting previously unselected package libct4:amd64.
Preparing to unpack .../21-libct4_1.00.82-2ubuntu0.1_amd64.deb ...
Unpacking libct4:amd64 (1.00.82-2ubuntu0.1) ...
Selecting previously unselected package libgdbm-compat4:amd64.
Preparing to unpack .../22-libgdbm-compat4_1.14.1-6_amd64.deb ...
Unpacking libgdbm-compat4:amd64 (1.14.1-6) ...
Selecting previously unselected package libperl5.26:amd64.
Preparing to unpack .../23-libperl5.26_5.26.1-6ubuntu0.3_amd64.deb ...
Unpacking libperl5.26:amd64 (5.26.1-6ubuntu0.3) ...
Selecting previously unselected package libpython2.7:amd64.
Preparing to unpack .../24-libpython2.7_2.7.17-1~18.04_amd64.deb ...
Unpacking libpython2.7:amd64 (2.7.17-1~18.04) ...
Selecting previously unselected package libtalloc2:amd64.
Preparing to unpack .../25-libtalloc2_2.1.10-2ubuntu1_amd64.deb ...
Unpacking libtalloc2:amd64 (2.1.10-2ubuntu1) ...
Selecting previously unselected package freeradius-common.
Preparing to unpack .../26-freeradius-common_3.0.16+dfsg-1ubuntu3.1_all.deb ...
Unpacking freeradius-common (3.0.16+dfsg-1ubuntu3.1) ...
Selecting previously unselected package make.
Preparing to unpack .../27-make_4.1-9.1ubuntu1_amd64.deb ...
Unpacking make (4.1-9.1ubuntu1) ...
Selecting previously unselected package ssl-cert.
Preparing to unpack .../28-ssl-cert_1.0.39_all.deb ...
Unpacking ssl-cert (1.0.39) ...
Selecting previously unselected package freeradius-config.
Preparing to unpack .../29-freeradius-config_3.0.16+dfsg-1ubuntu3.1_amd64.deb ...
Unpacking freeradius-config (3.0.16+dfsg-1ubuntu3.1) ...
Selecting previously unselected package libfreeradius3.
Preparing to unpack .../30-libfreeradius3_3.0.16+dfsg-1ubuntu3.1_amd64.deb ...
Unpacking libfreeradius3 (3.0.16+dfsg-1ubuntu3.1) ...
Selecting previously unselected package freeradius.
Preparing to unpack .../31-freeradius_3.0.16+dfsg-1ubuntu3.1_amd64.deb ...
Unpacking freeradius (3.0.16+dfsg-1ubuntu3.1) ...
Selecting previously unselected package netcat-traditional.
Preparing to unpack .../32-netcat-traditional_1.10-41.1_amd64.deb ...
Unpacking netcat-traditional (1.10-41.1) ...
Selecting previously unselected package netcat.
Preparing to unpack .../33-netcat_1.10-41.1_all.deb ...
Unpacking netcat (1.10-41.1) ...
Setting up make (4.1-9.1ubuntu1) ...
Setting up libip4tc0:amd64 (1.6.1-2ubuntu2) ...
Setting up freeradius-common (3.0.16+dfsg-1ubuntu3.1) ...
Adding user freerad to group shadow
Setting up netcat-traditional (1.10-41.1) ...
update-alternatives: using /bin/nc.traditional to provide /bin/nc (nc) in auto mode
update-alternatives: warning: skip creation of /usr/share/man/man1/nc.1.gz because associated file /usr/share/man/man1/nc.traditional.1.gz (of link group nc) doesn't exist
update-alternatives: warning: skip creation of /usr/share/man/man1/netcat.1.gz because associated file /usr/share/man/man1/nc.traditional.1.gz (of link group nc) doesn't exist
Setting up libiptc0:amd64 (1.6.1-2ubuntu2) ...
Setting up freetds-common (1.00.82-2ubuntu0.1) ...
Setting up perl-modules-5.26 (5.26.1-6ubuntu0.3) ...
Setting up libgdbm5:amd64 (1.14.1-6) ...
Setting up libbsd0:amd64 (0.8.7-1ubuntu0.1) ...
Setting up libkrb5support0:amd64 (1.16-2ubuntu0.1) ...
Setting up libxtables12:amd64 (1.6.1-2ubuntu2) ...
Setting up netcat (1.10-41.1) ...
Setting up openssl (1.1.1-1ubuntu2.1~18.04.5) ...
Setting up libnfnetlink0:amd64 (1.0.1-3) ...
Setting up libtalloc2:amd64 (2.1.10-2ubuntu1) ...
Setting up libgdbm-compat4:amd64 (1.14.1-6) ...
Setting up ethtool (1:4.15-0ubuntu1) ...
Setting up libkeyutils1:amd64 (1.5.9-9.2ubuntu2) ...
Setting up ca-certificates (20180409) ...
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
Updating certificates in /etc/ssl/certs...
133 added, 0 removed; done.
Setting up libpython2.7-stdlib:amd64 (2.7.17-1~18.04) ...
Setting up libdbus-1-3:amd64 (1.12.2-1ubuntu1.1) ...
Setting up libip6tc0:amd64 (1.6.1-2ubuntu2) ...
Setting up netbase (5.4) ...
Setting up libwbclient0:amd64 (2:4.7.6+dfsg~ubuntu-0ubuntu2.15) ...
Setting up libk5crypto3:amd64 (1.16-2ubuntu0.1) ...
Setting up libnetfilter-conntrack3:amd64 (1.0.6-2) ...
Setting up python2.7 (2.7.17-1~18.04) ...
Setting up ssl-cert (1.0.39) ...
debconf: unable to initialize frontend: Dialog
debconf: (TERM is not set, so the dialog frontend is not usable.)
debconf: falling back to frontend: Readline
debconf: unable to initialize frontend: Readline
debconf: (This frontend requires a controlling tty.)
debconf: falling back to frontend: Teletype
Setting up libfreeradius3 (3.0.16+dfsg-1ubuntu3.1) ...
Setting up freeradius-config (3.0.16+dfsg-1ubuntu3.1) ...
Adding user freerad to group ssl-cert
Generating DH parameters, 1024 bit long safe prime, generator 2
This is going to take a long time
..........................................................................................................+...............................................................................................................+.....+............................................................................................................+.........+...........................................+..............................................................................+................+......+.......+..................................................................+...........................+.+.....................................................+........................+..................+......................................+.................................................+..........................................................................................................................+..................................................................................+.................................................................................+..........+................................................................................................+................................................................................+.................................................................+.................................+.................................................................+....++*++*++*++*++*
Setting up libpython-stdlib:amd64 (2.7.15~rc1-1) ...
Setting up libperl5.26:amd64 (5.26.1-6ubuntu0.3) ...
Setting up iptables (1.6.1-2ubuntu2) ...
Setting up libpython2.7:amd64 (2.7.17-1~18.04) ...
Setting up dnsmasq-base (2.79-1) ...
Setting up libkrb5-3:amd64 (1.16-2ubuntu0.1) ...
Setting up python (2.7.15~rc1-1) ...
Setting up libgssapi-krb5-2:amd64 (1.16-2ubuntu0.1) ...
Setting up dnsmasq (2.79-1) ...
invoke-rc.d: could not determine current runlevel
invoke-rc.d: policy-rc.d denied execution of start.
Setting up libct4:amd64 (1.00.82-2ubuntu0.1) ...
Setting up freeradius (3.0.16+dfsg-1ubuntu3.1) ...
update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults
invoke-rc.d: could not determine current runlevel
invoke-rc.d: policy-rc.d denied execution of start.
Processing triggers for mime-support (3.60ubuntu1) ...
Processing triggers for libc-bin (2.27-3ubuntu1) ...
Processing triggers for ca-certificates (20180409) ...
Updating certificates in /etc/ssl/certs...
0 added, 0 removed; done.
Running hooks in /etc/ca-certificates/update.d...
done.
Removing intermediate container b6a6db6fb7ec
 ---> 4bfad8b1cf95
Step 3/5 : COPY misc/networking_scripts/* ./
 ---> 327de36d3cd4
Step 4/5 : RUN mv /usr/sbin/tcpdump /usr/bin/tcpdump
 ---> Running in 45cbae7f1701
Removing intermediate container 45cbae7f1701
 ---> b33fa039d826
Step 5/5 : ENTRYPOINT ["./start_networking"]
 ---> Running in 36b26ad860a8
Removing intermediate container 36b26ad860a8
 ---> c974667de649
Successfully built c974667de649
Successfully tagged daqf/networking:latest
Build complete.

[grafnu]

Is there a reason your head isn't identical to faucetsdn/master? I would expect the below. Some other things in your run seem wrong, so just want to make sure we're running the same stuff. If you intend for your repo master to match, you should do a "git reset --hard faucet/master" (or equivalent) to force it to match (and then a force push to your local repo). ~/daq$ git log -n 1 commit 7d201a9 (HEAD -> master, origin/master, origin/HEAD, faucet/master) Author: henry54809 <haolidu@google.com> Date: Tue Mar 31 09:42:26 2020 -0700 glinux setup script (#330)

…

On Tue, Mar 31, 2020 at 2:00 PM Francesco Anselmo ***@***.***> wrote: I have synced the repo again, and here is the output of git log -n 1 ***@***.***:~/Code/daq/pisuke/daq# git log -1 commit 92b6150 (HEAD -> master, origin/master, origin/HEAD) Merge: 7d201a9 d3d3ede Author: Francesco Anselmo ***@***.***> Date: Tue Mar 31 19:33:11 2020 +0100 Merge branch 'master' of https://github.com/pisuke/daq It looks in sync with the latest commit to me. I've re-built DAQ and here is a new cli log. Still not looking good unfortunately. ***@***.***:~/Code/daq/pisuke/daq# cmd/run -s Activating venv Loading config from local/system.conf into inst/config/system.conf Starting Tue 31 Mar 21:50:30 BST 2020 Clearing previous state... Activating venv Loading config from local/system.conf into inst/config/system.conf HEAD is now at f13396aa Merge pull request #3476 from mab68/orderedacl1 HEAD is now at b03e4666 Built docker images (#57) Release version release-1.0.1-159-g92b6150 ovsdb-server is running with pid 413 ovs-vswitchd is running with pid 480 Creating mudacl templates... Starting a Gradle Daemon, 1 incompatible Daemon could not be reused, use --status for details > Task :compileJava UP-TO-DATE > Task :processResources NO-SOURCE > Task :classes UP-TO-DATE > Task :shadowJar UP-TO-DATE Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0. Use '--warning-mode all' to show the individual deprecation warnings. See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings BUILD SUCCESSFUL in 6s 2 actionable tasks: 2 up-to-date Running mudacl regression test... Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/acl_templates Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/port_acls Compare out/acl_templates/ with setup/acl_templates/... Compare out/port_acls/ with setup/port_acls/... Activating venv Loading config from local/system.conf into inst/config/system.conf > Task :compileJava UP-TO-DATE > Task :processResources NO-SOURCE > Task :classes UP-TO-DATE > Task :shadowJar UP-TO-DATE Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0. Use '--warning-mode all' to show the individual deprecation warnings. See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings BUILD SUCCESSFUL in 775ms 2 actionable tasks: 2 up-to-date Executing mudacl generator on mud_files/... Writing output files to /home/francesco/Code/daq/pisuke/daq/inst/acl_templates inst/acl_templates: total 40 -rw-r--r-- 1 root root 540 Mar 31 21:50 template_bacnet_acl.yaml -rw-r--r-- 1 root root 332 Mar 31 21:50 template_bacnet_frdev_acl.yaml -rw-r--r-- 1 root root 332 Mar 31 21:50 template_bacnet_todev_acl.yaml -rw-r--r-- 1 root root 768 Mar 31 21:50 template_baseline_acl.yaml -rw-r--r-- 1 root root 1966 Mar 31 21:50 template_commissioning_acl.yaml -rw-r--r-- 1 root root 1452 Mar 31 21:50 template_controller_acl.yaml -rw-r--r-- 1 root root 78 Mar 31 21:50 template_default_acl.yaml -rw-r--r-- 1 root root 1442 Mar 31 21:50 template_lightbulb_acl.yaml -rw-r--r-- 1 root root 258 Mar 31 21:50 template_raw_acl.yaml -rw-r--r-- 1 root root 496 Mar 31 21:50 template_telnet_acl.yaml inst/port_acls: total 0 No external switch configured. Sourcing misc/startup_base.cmd... DAQ autostart cmd/faux Activating venv Loading config from local/system.conf into inst/config/system.conf Launching faux ... DAQ autoclean docker kill daq-faux Removing old interface faux Adding new interface to 14649... Done with faux device launch. Entering virtual python environment... Using python3 at /home/francesco/Code/daq/pisuke/daq/venv/bin/python3 Executing: python3 -u daq/daq.py /home/francesco/Code/daq/pisuke/daq/local/system.conf -s Prepending /home/francesco/Code/daq/pisuke/daq/binhack to PATH processing arg: /home/francesco/Code/daq/pisuke/daq/local/system.conf Reading config from /home/francesco/Code/daq/pisuke/daq/local/system.conf Reading config from misc/system.conf processing arg: -s base_conf=misc/module_config.json dhcp_lease_time=500s initial_dhcp_lease_time=120s intf_names=faux long_dhcp_response_sec=105 run_mode=local single_shot=True site_description="Baseline Configuration" site_path=local/site/ startup_cmds=misc/startup_base.cmd INFO:daq:pid is 14750 INFO:gcp:No gcp_cred credential specified in config INFO:config:Loading config from misc/module_config.json INFO:config:Skipping missing local/site/module_config.json INFO:topology:No device_specs file specified, skipping... INFO:runner:Reading test definition file misc/host_tests.conf INFO:runner:Reading test definition file subset/pentests/build.conf INFO:runner:Configured with tests ['pass', 'fail', 'ping', 'bacnet', 'mudgee', 'nmap', 'discover'] INFO:network:Activating faucet topology... INFO:topology:Starting faucet... INFO:network:Initializing faucitizer... INFO:network:Creating ovs sec with dpid/port 2/7 INFO:network:Added switch link pri-eth1 <-> sec-eth7 INFO:network:Attaching device interface faux on port 1. INFO:network:Attaching device interface sec-2 on port 2. INFO:network:Attaching device interface sec-3 on port 3. INFO:network:Attaching device interface sec-4 on port 4. INFO:network:Attaching device interface sec-5 on port 5. INFO:network:Attaching device interface sec-6 on port 6. INFO:network:Starting mininet... INFO:mininet:*** Configuring hosts INFO:mininet:*** Starting controller INFO:mininet:controller INFO:mininet:*** Starting 2 switches INFO:mininet:pri INFO:mininet:sec INFO:mininet:... INFO:runner:Waiting for system to settle... INFO:runner:Entering main event loop. INFO:runner:See docs/troubleshooting.md if this blocks for more than a few minutes. INFO:runner:System port 7 on dpid 2 is active True INFO:runner:Port 1 dpid 2 is now active True INFO:runner:Port 1 dpid 2 learned 9a:02:57:1e:8f:00 INFO:runner:Gateway for device group 9a02571e8f00 not found, initializing base 1... INFO:gateway:Initializing gateway 9a02571e8f00 as gw01/10 INFO:gateway:Added networking host gw01 on port 10 at 10.20.0.1 INFO:gateway:Added dummy target dummy01 on port 11 at 10.20.0.2 INFO:gateway:Gateway 1 startup capture gw01-eth0 in container's /tmp/gateway.pcap INFO:dhcp:DHCP monitor gw01 waiting for replies... INFO:runner:Test ping gw01->dummy01 INFO:runner:Test ping gw01->dummy01 INFO:runner:Test ping dummy01->gw01 INFO:runner:Test ping dummy01->192.168.84.1 INFO:runner:Test ping gw01->dummy01 from 192.168.84.1 INFO:gateway:Attaching target 1 to gateway group 9a02571e8f00 INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json INFO:host:Device config reloaded: True 9a:02:57:1e:8f:00 INFO:config:Writing config to /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/aux/module_config.json INFO:host:Host 1 running with enabled tests ['pass', 'fail', 'ping', 'nmap'] INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T205122+0000.md INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T205122+0000.pdf INFO:runner:Target port 1 registered 9a:02:57:1e:8f:00 INFO:host:Target port 1 initializing... INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json INFO:network:Creating mirror pair mirror-1 <-> mirror-1-ext at 1001 INFO:host:Target port 1 startup pcap capture INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: True INFO:topology:Waiting 5s for network to settle INFO:host:Target port 1 waiting for ip as 9a:02:57:1e:8f:00 INFO:host:Target port 1 using normal DHCP mode ERROR:host:Monitoring timeout for ipaddr after 300s ERROR:host:Target port 1 monitor error: Timeout expired INFO:host:Target port 1 monitor scan complete ERROR:runner:Target port 1 active True exception: Timeout expired INFO:gateway:Detach target 1 from gateway group 9a02571e8f00 INFO:runner:Retiring target gateway 1, 9a:02:57:1e:8f:00, 9a02571e8f00, 1 INFO:gateway:Terminating gateway 1/9a02571e8f00 INFO:runner:Target port 1 finalize: ['01:ipaddr:TimeoutError'] (Timeout expired) INFO:runner:Target port 1 cancel 9a:02:57:1e:8f:00 (#1/0). INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: False INFO:topology:Waiting 5s for network to settle INFO:host:Target port 1 terminate, running unknown, trigger False: _target_set_cancel INFO:network:Deleting mirror pair mirror-1 <-> mirror-1-ext WARNING:runner:Suppressing future tests because test done in single shot. INFO:runner:Remaining target sets: [] INFO:stream:Monitoring 0 fds WARNING:runner:No active ports remaining (0 monitors), ending test run. INFO:runner:Stopping network... INFO:mininet:*** Stopping 1 controllers INFO:mininet:controller INFO:mininet:*** Stopping 1 links INFO:mininet:. INFO:mininet:*** Stopping 2 switches INFO:mininet:pri INFO:mininet:sec INFO:mininet:*** Stopping 1 hosts INFO:mininet:gw01 INFO:mininet:*** Done INFO:runner:Done with runner. INFO:daq:DAQ runner returned 0 Cleanup docker kill daq-faux daq-faux Done with run, exit 0 — You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub <#334 (comment)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AAIEPD56JS7CIYOAZ65G2S3RKJKYNANCNFSM4LXRL7HA> .

[pisuke]

I've done a reset:

root@arup-daq-lab:~/Code/daq/pisuke/daq# git reset --hard upstream/master
HEAD is now at 7d201a9 glinux setup script (#330)
root@arup-daq-lab:~/Code/daq/pisuke/daq# git log -n 1
commit 7d201a9103653dacc2b41510314cf5a698a7a369 (HEAD -> master, upstream/master)
Author: henry54809 <haolidu@google.com>
Date:   Tue Mar 31 09:42:26 2020 -0700

    glinux setup script (#330)

It shows exactly the same hash as yours.

But then same problem:

root@arup-daq-lab:~/Code/daq/pisuke/daq# cmd/run -s
Activating venv
No local/system.conf found, copying defaults from misc/system_base.conf...
Loading config from local/system.conf into inst/config/system.conf
Starting Wed 1 Apr 00:52:34 BST 2020
Clearing previous state...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
HEAD is now at f13396aa Merge pull request #3476 from mab68/orderedacl1
HEAD is now at b03e4666 Built docker images (#57)
Release version release-1.0.1-146-g7d201a9
ovsdb-server is running with pid 413
ovs-vswitchd is running with pid 480
Creating mudacl templates...
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 677ms
2 actionable tasks: 2 up-to-date
Running mudacl regression test...
Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/acl_templates
Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/port_acls
Compare out/acl_templates/ with setup/acl_templates/...
Compare out/port_acls/ with setup/port_acls/...
Activating venv
Loading config from local/system.conf into inst/config/system.conf
> Task :compileJava UP-TO-DATE
> Task :processResources NO-SOURCE
> Task :classes UP-TO-DATE
> Task :shadowJar UP-TO-DATE

Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings

BUILD SUCCESSFUL in 692ms
2 actionable tasks: 2 up-to-date

Executing mudacl generator on mud_files/...
Writing output files to /home/francesco/Code/daq/pisuke/daq/inst/acl_templates
inst/acl_templates:
total 40
-rw-r--r-- 1 root root  540 Apr  1 00:52 template_bacnet_acl.yaml
-rw-r--r-- 1 root root  332 Apr  1 00:52 template_bacnet_frdev_acl.yaml
-rw-r--r-- 1 root root  332 Apr  1 00:52 template_bacnet_todev_acl.yaml
-rw-r--r-- 1 root root  768 Apr  1 00:52 template_baseline_acl.yaml
-rw-r--r-- 1 root root 1966 Apr  1 00:52 template_commissioning_acl.yaml
-rw-r--r-- 1 root root 1452 Apr  1 00:52 template_controller_acl.yaml
-rw-r--r-- 1 root root   78 Apr  1 00:52 template_default_acl.yaml
-rw-r--r-- 1 root root 1442 Apr  1 00:52 template_lightbulb_acl.yaml
-rw-r--r-- 1 root root  258 Apr  1 00:52 template_raw_acl.yaml
-rw-r--r-- 1 root root  496 Apr  1 00:52 template_telnet_acl.yaml

inst/port_acls:
total 0
No external switch configured.
Sourcing misc/startup_base.cmd...
DAQ autostart cmd/faux
Activating venv
Loading config from local/system.conf into inst/config/system.conf
Launching faux ...
DAQ autoclean docker kill daq-faux
Removing old interface faux
Adding new interface to 22045...
Done with faux device launch.
Entering virtual python environment...
Using python3 at /home/francesco/Code/daq/pisuke/daq/venv/bin/python3
Executing: python3 -u daq/daq.py /home/francesco/Code/daq/pisuke/daq/local/system.conf -s
Prepending /home/francesco/Code/daq/pisuke/daq/binhack to PATH
processing arg: /home/francesco/Code/daq/pisuke/daq/local/system.conf
Reading config from /home/francesco/Code/daq/pisuke/daq/local/system.conf
Reading config from misc/system.conf
processing arg: -s
base_conf=misc/module_config.json
dhcp_lease_time=500s
initial_dhcp_lease_time=120s
intf_names=faux
long_dhcp_response_sec=105
run_mode=local
single_shot=True
site_description="Baseline Configuration"
site_path=local/site/
startup_cmds=misc/startup_base.cmd
INFO:daq:pid is 22135
INFO:gcp:No gcp_cred credential specified in config
INFO:config:Loading config from misc/module_config.json
INFO:config:Skipping missing local/site/module_config.json
INFO:topology:No device_specs file specified, skipping...
INFO:runner:Reading test definition file misc/host_tests.conf
INFO:runner:Reading test definition file subset/pentests/build.conf
INFO:runner:Configured with tests ['pass', 'fail', 'ping', 'bacnet', 'mudgee', 'nmap', 'discover']
INFO:network:Activating faucet topology...
INFO:topology:Starting faucet...
INFO:network:Initializing faucitizer...
INFO:network:Creating ovs sec with dpid/port 2/7
INFO:network:Added switch link pri-eth1 <-> sec-eth7
INFO:network:Attaching device interface faux on port 1.
INFO:network:Attaching device interface sec-2 on port 2.
INFO:network:Attaching device interface sec-3 on port 3.
INFO:network:Attaching device interface sec-4 on port 4.
INFO:network:Attaching device interface sec-5 on port 5.
INFO:network:Attaching device interface sec-6 on port 6.
INFO:network:Starting mininet...
INFO:mininet:*** Configuring hosts
INFO:mininet:*** Starting controller
INFO:mininet:controller
INFO:mininet:*** Starting 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:...
INFO:runner:Waiting for system to settle...
INFO:runner:Entering main event loop.
INFO:runner:See docs/troubleshooting.md if this blocks for more than a few minutes.
INFO:runner:System port 7 on dpid 2 is active True
INFO:runner:Port 1 dpid 2 is now active True
INFO:runner:Port 1 dpid 2 learned 9a:02:57:1e:8f:00
INFO:runner:Gateway for device group 9a02571e8f00 not found, initializing base 1...
INFO:gateway:Initializing gateway 9a02571e8f00 as gw01/10
INFO:gateway:Added networking host gw01 on port 10 at 10.20.0.1
INFO:gateway:Added dummy target dummy01 on port 11 at 10.20.0.2
INFO:gateway:Gateway 1 startup capture gw01-eth0 in container's /tmp/gateway.pcap
INFO:dhcp:DHCP monitor gw01 waiting for replies...
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping gw01->dummy01
INFO:runner:Test ping dummy01->gw01
INFO:runner:Test ping dummy01->192.168.84.1
INFO:runner:Test ping gw01->dummy01 from 192.168.84.1
INFO:gateway:Attaching target 1 to gateway group 9a02571e8f00
WARNING:host:Creating new device dir: /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00
WARNING:host:Skipping defaults since no device_template found
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:host:Device config reloaded: True 9a:02:57:1e:8f:00
INFO:config:Writing config to /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/aux/module_config.json
INFO:host:Host 1 running with enabled tests ['pass', 'fail', 'ping', 'nmap']
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T235314+0000.md
INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T235314+0000.pdf
INFO:runner:Target port 1 registered 9a:02:57:1e:8f:00
INFO:host:Target port 1 initializing...
INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json
INFO:network:Creating mirror pair mirror-1 <-> mirror-1-ext at 1001
INFO:host:Target port 1 startup pcap capture
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: True
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 waiting for ip as 9a:02:57:1e:8f:00
INFO:host:Target port 1 using normal DHCP mode
ERROR:host:Monitoring timeout for ipaddr after 300s
ERROR:host:Target port 1 monitor error: Timeout expired
INFO:host:Target port 1 monitor scan complete
ERROR:runner:Target port 1 active True exception: Timeout expired
INFO:gateway:Detach target 1 from gateway group 9a02571e8f00
INFO:runner:Retiring target gateway 1, 9a:02:57:1e:8f:00, 9a02571e8f00, 1
INFO:gateway:Terminating gateway 1/9a02571e8f00
INFO:runner:Target port 1 finalize: ['01:ipaddr:TimeoutError'] (Timeout expired)
INFO:runner:Target port 1 cancel 9a:02:57:1e:8f:00 (#1/0).
INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: False
INFO:topology:Waiting 5s for network to settle
INFO:host:Target port 1 terminate, running unknown, trigger False: _target_set_cancel
INFO:network:Deleting mirror pair mirror-1 <-> mirror-1-ext
WARNING:runner:Suppressing future tests because test done in single shot.
INFO:runner:Remaining target sets: []
INFO:stream:Monitoring 0 fds 
WARNING:runner:No active ports remaining (0 monitors), ending test run.
INFO:runner:Stopping network...
INFO:mininet:*** Stopping 1 controllers
INFO:mininet:controller
INFO:mininet:*** Stopping 1 links
INFO:mininet:.
INFO:mininet:*** Stopping 2 switches
INFO:mininet:pri
INFO:mininet:sec
INFO:mininet:*** Stopping 1 hosts
INFO:mininet:gw01
INFO:mininet:*** Done
INFO:runner:Done with runner.
INFO:daq:DAQ runner returned 0
Cleanup docker kill daq-faux
daq-faux
Done with run, exit 0

Not sure what else I can do apart from rolling back to about one week ago when it was working.

[pisuke]

@henry54809 @grafnu are you not able to replicate this?

A git diff doesn't show any difference between my upstream/master (faucetsdn) and my local master.

Really puzzled ...

[grafnu]

Release version release-1.0.1-146-g7d201a9 That is still very much not right! What happens if you type "git describe --dirty" in your daq directory? Oh wait -- maybe you need to fetch tags too... try "git pull upstream --tags"

…

On Tue, Mar 31, 2020 at 4:56 PM Francesco Anselmo ***@***.***> wrote: I've done a reset: ***@***.***:~/Code/daq/pisuke/daq# git reset --hard upstream/master HEAD is now at 7d201a9 glinux setup script (#330) ***@***.***:~/Code/daq/pisuke/daq# git log -n 1 commit 7d201a9 (HEAD -> master, upstream/master) Author: henry54809 ***@***.***> Date: Tue Mar 31 09:42:26 2020 -0700 glinux setup script (#330) It shows exactly the same hash as yours. But then same problem: ***@***.***:~/Code/daq/pisuke/daq# cmd/run -s Activating venv No local/system.conf found, copying defaults from misc/system_base.conf... Loading config from local/system.conf into inst/config/system.conf Starting Wed 1 Apr 00:52:34 BST 2020 Clearing previous state... Activating venv Loading config from local/system.conf into inst/config/system.conf HEAD is now at f13396aa Merge pull request #3476 from mab68/orderedacl1 HEAD is now at b03e4666 Built docker images (#57) Release version release-1.0.1-146-g7d201a9 ovsdb-server is running with pid 413 ovs-vswitchd is running with pid 480 Creating mudacl templates... > Task :compileJava UP-TO-DATE > Task :processResources NO-SOURCE > Task :classes UP-TO-DATE > Task :shadowJar UP-TO-DATE Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0. Use '--warning-mode all' to show the individual deprecation warnings. See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings BUILD SUCCESSFUL in 677ms 2 actionable tasks: 2 up-to-date Running mudacl regression test... Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/acl_templates Writing output files to /home/francesco/Code/daq/pisuke/daq/mudacl/out/port_acls Compare out/acl_templates/ with setup/acl_templates/... Compare out/port_acls/ with setup/port_acls/... Activating venv Loading config from local/system.conf into inst/config/system.conf > Task :compileJava UP-TO-DATE > Task :processResources NO-SOURCE > Task :classes UP-TO-DATE > Task :shadowJar UP-TO-DATE Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0. Use '--warning-mode all' to show the individual deprecation warnings. See https://docs.gradle.org/6.3/userguide/command_line_interface.html#sec:command_line_warnings BUILD SUCCESSFUL in 692ms 2 actionable tasks: 2 up-to-date Executing mudacl generator on mud_files/... Writing output files to /home/francesco/Code/daq/pisuke/daq/inst/acl_templates inst/acl_templates: total 40 -rw-r--r-- 1 root root 540 Apr 1 00:52 template_bacnet_acl.yaml -rw-r--r-- 1 root root 332 Apr 1 00:52 template_bacnet_frdev_acl.yaml -rw-r--r-- 1 root root 332 Apr 1 00:52 template_bacnet_todev_acl.yaml -rw-r--r-- 1 root root 768 Apr 1 00:52 template_baseline_acl.yaml -rw-r--r-- 1 root root 1966 Apr 1 00:52 template_commissioning_acl.yaml -rw-r--r-- 1 root root 1452 Apr 1 00:52 template_controller_acl.yaml -rw-r--r-- 1 root root 78 Apr 1 00:52 template_default_acl.yaml -rw-r--r-- 1 root root 1442 Apr 1 00:52 template_lightbulb_acl.yaml -rw-r--r-- 1 root root 258 Apr 1 00:52 template_raw_acl.yaml -rw-r--r-- 1 root root 496 Apr 1 00:52 template_telnet_acl.yaml inst/port_acls: total 0 No external switch configured. Sourcing misc/startup_base.cmd... DAQ autostart cmd/faux Activating venv Loading config from local/system.conf into inst/config/system.conf Launching faux ... DAQ autoclean docker kill daq-faux Removing old interface faux Adding new interface to 22045... Done with faux device launch. Entering virtual python environment... Using python3 at /home/francesco/Code/daq/pisuke/daq/venv/bin/python3 Executing: python3 -u daq/daq.py /home/francesco/Code/daq/pisuke/daq/local/system.conf -s Prepending /home/francesco/Code/daq/pisuke/daq/binhack to PATH processing arg: /home/francesco/Code/daq/pisuke/daq/local/system.conf Reading config from /home/francesco/Code/daq/pisuke/daq/local/system.conf Reading config from misc/system.conf processing arg: -s base_conf=misc/module_config.json dhcp_lease_time=500s initial_dhcp_lease_time=120s intf_names=faux long_dhcp_response_sec=105 run_mode=local single_shot=True site_description="Baseline Configuration" site_path=local/site/ startup_cmds=misc/startup_base.cmd INFO:daq:pid is 22135 INFO:gcp:No gcp_cred credential specified in config INFO:config:Loading config from misc/module_config.json INFO:config:Skipping missing local/site/module_config.json INFO:topology:No device_specs file specified, skipping... INFO:runner:Reading test definition file misc/host_tests.conf INFO:runner:Reading test definition file subset/pentests/build.conf INFO:runner:Configured with tests ['pass', 'fail', 'ping', 'bacnet', 'mudgee', 'nmap', 'discover'] INFO:network:Activating faucet topology... INFO:topology:Starting faucet... INFO:network:Initializing faucitizer... INFO:network:Creating ovs sec with dpid/port 2/7 INFO:network:Added switch link pri-eth1 <-> sec-eth7 INFO:network:Attaching device interface faux on port 1. INFO:network:Attaching device interface sec-2 on port 2. INFO:network:Attaching device interface sec-3 on port 3. INFO:network:Attaching device interface sec-4 on port 4. INFO:network:Attaching device interface sec-5 on port 5. INFO:network:Attaching device interface sec-6 on port 6. INFO:network:Starting mininet... INFO:mininet:*** Configuring hosts INFO:mininet:*** Starting controller INFO:mininet:controller INFO:mininet:*** Starting 2 switches INFO:mininet:pri INFO:mininet:sec INFO:mininet:... INFO:runner:Waiting for system to settle... INFO:runner:Entering main event loop. INFO:runner:See docs/troubleshooting.md if this blocks for more than a few minutes. INFO:runner:System port 7 on dpid 2 is active True INFO:runner:Port 1 dpid 2 is now active True INFO:runner:Port 1 dpid 2 learned 9a:02:57:1e:8f:00 INFO:runner:Gateway for device group 9a02571e8f00 not found, initializing base 1... INFO:gateway:Initializing gateway 9a02571e8f00 as gw01/10 INFO:gateway:Added networking host gw01 on port 10 at 10.20.0.1 INFO:gateway:Added dummy target dummy01 on port 11 at 10.20.0.2 INFO:gateway:Gateway 1 startup capture gw01-eth0 in container's /tmp/gateway.pcap INFO:dhcp:DHCP monitor gw01 waiting for replies... INFO:runner:Test ping gw01->dummy01 INFO:runner:Test ping gw01->dummy01 INFO:runner:Test ping dummy01->gw01 INFO:runner:Test ping dummy01->192.168.84.1 INFO:runner:Test ping gw01->dummy01 from 192.168.84.1 INFO:gateway:Attaching target 1 to gateway group 9a02571e8f00 WARNING:host:Creating new device dir: /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00 WARNING:host:Skipping defaults since no device_template found INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json INFO:host:Device config reloaded: True 9a:02:57:1e:8f:00 INFO:config:Writing config to /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/aux/module_config.json INFO:host:Host 1 running with enabled tests ['pass', 'fail', 'ping', 'nmap'] INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T235314+0000.md INFO:report:Creating report as inst/reports/report_9a02571e8f00_2020-03-31T235314+0000.pdf INFO:runner:Target port 1 registered 9a:02:57:1e:8f:00 INFO:host:Target port 1 initializing... INFO:config:Skipping missing /home/francesco/Code/daq/pisuke/daq/local/site/mac_addrs/9a02571e8f00/module_config.json INFO:network:Creating mirror pair mirror-1 <-> mirror-1-ext at 1001 INFO:host:Target port 1 startup pcap capture INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: True INFO:topology:Waiting 5s for network to settle INFO:host:Target port 1 waiting for ip as 9a:02:57:1e:8f:00 INFO:host:Target port 1 using normal DHCP mode ERROR:host:Monitoring timeout for ipaddr after 300s ERROR:host:Target port 1 monitor error: Timeout expired INFO:host:Target port 1 monitor scan complete ERROR:runner:Target port 1 active True exception: Timeout expired INFO:gateway:Detach target 1 from gateway group 9a02571e8f00 INFO:runner:Retiring target gateway 1, 9a:02:57:1e:8f:00, 9a02571e8f00, 1 INFO:gateway:Terminating gateway 1/9a02571e8f00 INFO:runner:Target port 1 finalize: ['01:ipaddr:TimeoutError'] (Timeout expired) INFO:runner:Target port 1 cancel 9a:02:57:1e:8f:00 (#1/0). INFO:network:Directing traffic for 9a:02:57:1e:8f:00 on port 1: False INFO:topology:Waiting 5s for network to settle INFO:host:Target port 1 terminate, running unknown, trigger False: _target_set_cancel INFO:network:Deleting mirror pair mirror-1 <-> mirror-1-ext WARNING:runner:Suppressing future tests because test done in single shot. INFO:runner:Remaining target sets: [] INFO:stream:Monitoring 0 fds WARNING:runner:No active ports remaining (0 monitors), ending test run. INFO:runner:Stopping network... INFO:mininet:*** Stopping 1 controllers INFO:mininet:controller INFO:mininet:*** Stopping 1 links INFO:mininet:. INFO:mininet:*** Stopping 2 switches INFO:mininet:pri INFO:mininet:sec INFO:mininet:*** Stopping 1 hosts INFO:mininet:gw01 INFO:mininet:*** Done INFO:runner:Done with runner. INFO:daq:DAQ runner returned 0 Cleanup docker kill daq-faux daq-faux Done with run, exit 0 Not sure what else I can do apart from rolling back to about one week ago when it was working. — You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub <#334 (comment)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AAIEPD6EOAAELZ44GXGUBEDRKJ7MXANCNFSM4LXRL7HA> .

[grafnu]

we are not able to replicate. We should try in a GCP VM... at least then to have a common point of reference. Also, can you try not doing it as root? That's not the right way to do things and so maybe is messing something up.

…

On Tue, Mar 31, 2020 at 4:58 PM Francesco Anselmo ***@***.***> wrote: @henry54809 <https://github.com/henry54809> @grafnu <https://github.com/grafnu> are you not able to replicate this? A git diff doesn't show any difference between my upstream/master (faucetsdn) and my local master. Really puzzled ... — You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub <#334 (comment)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AAIEPDY26JD5XX62DAGR7ZLRKJ7TPANCNFSM4LXRL7HA> .