(spinnaker) giorgio@giorgio-pc:~/octopus/git_projects/spinnaker$ python dvs_hello_world.py
['/home/giorgio/spinnaker/lib/python3.8/site-packages/spinn_front_end_common/interface/spinnaker.cfg', '/home/giorgio/spinnaker/lib/python3.8/site-packages/spynnaker/pyNN/spynnaker.cfg', '/home/giorgio/.spynnaker.cfg', './spynnaker.cfg']
2022-03-09 16:54:57 INFO: Read cfg files: /home/giorgio/spinnaker/lib/python3.8/site-packages/spinn_front_end_common/interface/spinnaker.cfg, /home/giorgio/spinnaker/lib/python3.8/site-packages/spynnaker/pyNN/spynnaker.cfg, /home/giorgio/.spynnaker.cfg, ./spynnaker.cfg
2022-03-09 16:54:57 INFO: Will search these locations for binaries: /home/giorgio/spinnaker/lib/python3.8/site-packages/spinn_front_end_common/common_model_binaries : /home/giorgio/spinnaker/lib/python3.8/site-packages/spynnaker/pyNN/model_binaries
2022-03-09 16:54:57 INFO: Setting time scale factor to 1.
2022-03-09 16:54:57 INFO: Setting machine time step to 1000 micro-seconds.
2022-03-09 16:54:57 INFO: Starting execution process
2022-03-09 16:54:57 INFO: Simulating for 10000 1.0ms timesteps using a hardware timestep of 1000us
2022-03-09 16:54:57 INFO: Creating transceiver for 192.168.240.253
2022-03-09 16:54:57 INFO: Working out if machine is booted
2022-03-09 16:54:57 INFO: Found board with version [Version: SC&MP 3.4.1 at SpiNNaker:0:0:0 (built Thu Feb 11 16:36:44 2021)]
2022-03-09 16:54:57 INFO: Machine communication successful
2022-03-09 16:54:57 INFO: Detected a machine on IP address 192.168.240.253 which has 72 cores and 8.0 links
2022-03-09 16:54:57 INFO: Time 0:00:00.004353 taken by MachineGenerator
Preallocating resources for Extra Monitor support vertices
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000194 taken by PreAllocateResourcesForExtraMonitorSupport
2022-03-09 16:54:57 INFO: Time 0:00:00.000228 taken by NetworkSpecificationReport
Allocating virtual identifiers
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000333 taken by MallocBasedChipIDAllocator
Writing the board chip report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000170 taken by BoardChipReport
Adding Splitter selectors where appropriate
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000205 taken by SpynnakerSplitterSelector
Adding delay extensions as required
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000331 taken by DelaySupportAdder
Partitioning graph vertices
|0%                          50%                         100%|
 ============================================================
Partitioning graph edges
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.001122 taken by SpYNNakerSplitterPartitioner
Inserting extra monitors into graphs
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000338 taken by InsertExtraMonitorVerticesToGraphs
Generating partitioner report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000231 taken by PartitionerReport
Getting number of keys required by each edge using application graph
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000131 taken by EdgeToNKeysMapper
2022-03-09 16:54:57 INFO: The time scale factor could be reduced to 0
2022-03-09 16:54:57 INFO: Time 0:00:00.000260 taken by LocalTDMABuilder
Placing graph vertices via spreading over an entire machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000760 taken by SpreaderPlacer
Inserting edges between vertices which require FR speed up functionality.
|0%                          50%                         100%|
 ==============================2022-03-09 16:54:57 INFO: Time 0:00:00.000324 taken by InsertEdgesToExtraMonitorFunctionality
Generating routing tables for data in system processes
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000211 taken by SystemMulticastRoutingGenerator
Generating fixed router routes
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000127 taken by FixedRouteRouter
Generating placement report
|0%                          50%                         100%|
 ============================================================
Generating placement by core report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000502 taken by PlacerReportWithApplicationGraph
Routing
|0%                          50%                         100%|
 ============================================================

2022-03-09 16:54:57 INFO: Time 0:00:00.000202 taken by NerRouteTrafficAware
Discovering tags
|0%                          50%                         100%|
 ============================================================
Allocating tags
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000358 taken by BasicTagAllocator
Reporting Tags
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000160 taken by TagReport
Getting constraints for machine graph
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000148 taken by ProcessPartitionConstraints
Calculating zones
|0%                          50%                         100%|
 ============================================================
Allocating routing keys
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.008286 taken by ZonedRoutingInfoAllocator
Generating Routing info report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000243 taken by routingInfoReports
Generating routing tables
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000165 taken by BasicRoutingTableGenerator
2022-03-09 16:54:57 INFO: Time 0:00:00.000094 taken by RouterCollisionPotentialReport
Finding executable start types
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000163 taken by LocateExecutableStartType
Initialising buffers
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.001495 taken by BufferManagerCreator
Allocating SDRAM for SDRAM outgoing egde partitions
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:57 INFO: Time 0:00:00.000127 taken by SDRAMOutgoingPartitionAllocator
Generating data specifications
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:58 INFO: Time 0:00:00.032383 taken by SpynnakerDataSpecificationWriter
Preparing Routing Tables
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:58 INFO: Time 0:00:00.001613 taken by RoutingSetup
Finding binaries
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:54:58 INFO: Time 0:00:00.000251 taken by GraphBinaryGatherer
Running pair routing table compression on chip
|0%                          50%                         100%|
 ============================================================

2022-03-09 16:55:01 INFO: Time 0:00:03.679457 taken by PairOnChipRouterCompression
Generating Router table report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:01 INFO: Time 0:00:00.005207 taken by unCompressedRoutingTableReports
loading fixed routes
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:01 INFO: Time 0:00:00.002517 taken by LoadFixedRoutes
Executing data specifications and loading data for system vertices
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:01 INFO: Time 0:00:00.017552 taken by HostExecuteSystemDataSpecification
Loading system executables onto the machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:08 INFO: Time 0:00:06.311131 taken by LoadSystemExecutableImages
2022-03-09 16:55:08 INFO: Time 0:00:00.002814 taken by TagsFromMachineReport
Clearing tags
|0%                          50%                         100%|
 ============================================================
Loading Tags
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:08 INFO: Time 0:00:00.001543 taken by TagsLoader
Executing data specifications and loading data for application vertices
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:08 INFO: Time 0:00:00.034212 taken by HostExecuteApplicationDataSpecification
Preparing to Expand Synapses
|0%                          50%                         100%|
 ============================================================
Expanding Synapses
|0%                          50%                         100%|
 ============================================================


2022-03-09 16:55:15 INFO: Time 0:00:07.246121 taken by SynapseExpander
Running bitfield generation on chip
|0%                          50%                         100%|
 ============================================================


2022-03-09 16:55:19 INFO: Time 0:00:04.591735 taken by OnChipBitFieldGenerator
Finalising Retrieved Connections
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.000547 taken by FinishConnectionHolders
Reading Routing Tables from Machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.005253 taken by ReadRoutingTablesFromMachine
Generating compressed router table report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.000362 taken by compressedRoutingTableReports
Generating comparison of router table report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.000225 taken by comparisonOfRoutingTablesReport
Generating Routing summary report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.000277 taken by CompressedRouterSummaryReport
Reading Routing Tables from Machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.000274 taken by RoutingTableFromMachineReport
Writing fixed route report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:19 INFO: Time 0:00:00.001262 taken by FixedRouteFromMachineReport
Loading executables onto the machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:24 INFO: Time 0:00:04.241604 taken by LoadApplicationExecutableImages
2022-03-09 16:55:24 INFO: Running for 1 steps for a total of 10000.0ms
2022-03-09 16:55:24 INFO: Run 1 of 1
Generating SDRAM usage report
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:24 INFO: Time 0:00:00.000625 taken by SdramUsageReportPerChip
2022-03-09 16:55:24 INFO: creating live event connection database in /home/giorgio/octopus/git_projects/spinnaker/reports/2022-03-09-16-54-57-914441/run_1/input_output_database.db
Creating graph description database
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:24 INFO: Time 0:00:00.232481 taken by DatabaseInterface
2022-03-09 16:55:24 INFO: ** Notifying external sources that the database is ready for reading **
2022-03-09 16:55:24 INFO: Time 0:00:00.001478 taken by CreateNotificationProtocol
Getting provenance data from machine graph
|0%                          50%                         100%|
 ============================================================
Getting provenance data from application graph
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:24 INFO: Time 0:00:00.001335 taken by GraphProvenanceGatherer
Waiting for cores to be either in PAUSED or READY state
|0%                          50%                         100%|
 ============================================================
Updating run time
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:24 INFO: Time 0:00:00.022802 taken by ChipRuntimeUpdater
2022-03-09 16:55:24 INFO: *** Running simulation... *** 
Loading buffers
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:24 INFO: ** Awaiting for a response from an external source to state its ready for the simulation to start **
2022-03-09 16:55:24 INFO: ** Sending start / resume message to external sources to state the simulation has started or resumed. **
2022-03-09 16:55:24 INFO: ** Awaiting for a response from an external source to state its ready for the simulation to start **
2022-03-09 16:55:24 INFO: Application started; waiting 10.1s for it to stop
2022-03-09 16:55:34 INFO: ** Sending pause / stop message to external sources to state the simulation has been paused or stopped. **
2022-03-09 16:55:34 INFO: Time 0:00:10.191125 taken by ApplicationRunner
Extracting IOBUF from the machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:34 INFO: Time 0:00:00.008577 taken by ChipIOBufExtractor
clearing IOBUF from the machine
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:34 INFO: Time 0:00:00.000497 taken by ChipIOBufClearer
2022-03-09 16:55:34 INFO: No recorded data to extract
2022-03-09 16:55:34 INFO: Time 0:00:00.000249 taken by BufferExtractor
2022-03-09 16:55:34 INFO: Time 0:00:00.000085 taken by FinaliseTimingData
Getting provenance data
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:34 INFO: Time 0:00:00.001161 taken by PlacementsProvenanceGatherer
2022-03-09 16:55:34 INFO: Time 0:00:00.000377 taken by RedundantPacketCountReport
Getting Router Provenance
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:34 INFO: Time 0:00:00.002570 taken by RouterProvenanceGatherer
Getting profile data
|0%                          50%                         100%|
 ============================================================
2022-03-09 16:55:34 INFO: Time 0:00:00.000801 taken by ProfileDataGatherer