2026-08-22 05:48:48[1][!][progress]@@@@@ Logging started for /mnt/c/LocalData/GeoDMS_Test_Results/20_16_0_l/log/t102_network_od_pc6_sparse.txt at 2026-08-22 05:48:48 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam.dms 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam/WegenVerbonden.dms 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam/WegenMetLokaties.dms 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam/XML.dms 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam/MakeSnapshot.dms 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam/MakeSnapshot/Afleidingen.dms 2026-08-22 05:48:48[1][.][other]load /mnt/c/Users/Cicada/prj/GeoDMS-Test/Regression/cfg/stam/../generic/TestResult.dms 2026-08-22 05:48:48[1][.][other]load /opt/ObjectVision/GeoDms20.16.0.l/prelude.dms 2026-08-22 05:48:48[1][!][progress]Item results/t102_network_od_pc6_sparse/result_json 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'IsHidden' as token 335 and then seen 'isHidden' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'string' as token 31 and then seen 'String' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Unit' as token 258 and then seen 'unit' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Using' as token 341 and then seen 'using' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'map' as token 79 and then seen 'Map' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'NrOfRows' as token 189 and then seen 'nrofrows' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Label' as token 94 and then seen 'label' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'isSnelWeg' as token 2676 and then seen 'IsSnelweg' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'NextPoint' as token 2553 and then seen 'nextpoint' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Values' as token 428 and then seen 'values' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'LinkSet' as token 2705 and then seen 'Linkset' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'ArcID' as token 2460 and then seen 'ArcId' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'xml' as token 384 and then seen 'XML' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'StorageName' as token 314 and then seen 'Storagename' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'template' as token 2743 and then seen 'Template' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'float64' as token 30 and then seen 'Float64' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Parent_rel' as token 431 and then seen 'parent_rel' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'int32' as token 24 and then seen 'Int32' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'polygon' as token 400 and then seen 'Polygon' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'ne' as token 151 and then seen 'NE' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'x' as token 2863 and then seen 'X' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'y' as token 2864 and then seen 'Y' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'status' as token 2909 and then seen 'STATUS' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'begindatum' as token 2910 and then seen 'BEGINDATUM' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'einddatum' as token 2911 and then seen 'EINDDATUM' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'is_inactief' as token 2912 and then seen 'IS_INACTIEF' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'pointset' as token 2679 and then seen 'PointSet' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Metric' as token 99 and then seen 'metric' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'GeoDmsVersion' as token 442 and then seen 'GeoDMSVersion' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'File' as token 2832 and then seen 'file' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'bool' as token 26 and then seen 'Bool' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'float32' as token 29 and then seen 'Float32' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'f' as token 405 and then seen 'F' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'd' as token 404 and then seen 'D' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'p' as token 2862 and then seen 'P' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'T' as token 3114 and then seen 't' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'None' as token 369 and then seen 'none' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'V' as token 2934 and then seen 'v' Regression: TreeItem 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'FSS' as token 388 and then seen 'fss' [[/brondata/weg]] 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'IsNull' as token 2166 and then seen 'isNull' [[/eenheden/point_rd]] 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'BaseUnit' as token 240 and then seen 'baseunit' [[/eenheden/point_rd]] 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'PartNr' as token 2467 and then seen 'partnr' [[/prelude]] 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'u' as token 406 and then seen 'U' [[/netwerk/WegenVerbonden/networks/nr_nodes]] 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'Value' as token 17 and then seen 'value' [[/netwerk/WegenVerbonden/LinkSet/pointset/ArcNr]] 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'id' as token 156 and then seen 'ID' [[/netwerk/WegenVerbonden/LinkSet/pointset/ArcNr]] 2026-08-22 05:48:48[1][.][performance]oper float32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[1][.][performance]oper float32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[1][C][progress]Depreciated mix-up of cases, tokenized 'LinkSet/pointset/point' as token 3197 and then seen 'linkset/pointset/point' [[/netwerk/WegenMetLokaties/PC6/LinkSet/pointset]] 2026-08-22 05:48:48[1][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[2][!][storage read]Read results_folder from /mnt/c/LocalData/regression/log/results_folder.txt [[/results/VersionInfo/results_folder]] 2026-08-22 05:48:48[2][.][performance]read /results/VersionInfo/results_folder: 1.6ms n=1 (1.00x derived) B=0 (1.00x) 0.0MB/s 1 chores 2026-08-22 05:48:48[1][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[1][.][progress]{ Updating::[[/results/t102_network_od_pc6_sparse/result_json]] 2026-08-22 05:48:48[3][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[3][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[4][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[3][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[5][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[5][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[6][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[5][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[1][!][storage read] Read weg from /mnt/d/SourceData/NetworkTests/roads_nl_be_du.fss [[/brondata/weg]] 2026-08-22 05:48:48[1][.][performance] read /brondata/weg: 0.8ms n=? est B=0 derived 2026-08-22 05:48:48[7][.][performance]oper union_unit [[/netwerk/WegenVerbonden/LinkSet/pointset]]: 0.0ms meta result, no data cost 2026-08-22 05:48:48[8][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[11][.][performance]oper NrOfRows : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[8][.][performance]oper convert : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:48[10][.][performance]oper id : DataItem: 0.0ms n=2647670 (1.00x derived) B=10.10M (1.00x) ops=2647670 deferred 41x256K res=10.10M !regime=streaming predicted=deferred 2026-08-22 05:48:48[10][.][performance]oper mod : DataItem: 0.0ms n=2647670 (1.00x derived) B=10.10M (1.00x) ops=2647670 deferred 41x256K res=10.10M argmat=5.91M 2026-08-22 05:48:48[2][.][performance]oper convert [[/netwerk/WegenVerbonden/LinkSet/pointset/ArcNr]]: 0.0ms n=2647670 (1.00x derived) B=10.10M (1.00x) ops=2647670 deferred 41x256K res=10.10M argmat=10.10M 2026-08-22 05:48:48[9][!][storage read]Read geometry from /mnt/d/SourceData/NetworkTests/roads_nl_be_du.fss [[/brondata/weg/geometry]] 2026-08-22 05:48:48[9][.][other]CreateAbstrHeapTileFunctor(attribute /brondata/weg/geometry(27 tiles)) 2026-08-22 05:48:48[1][!][storage read] Read PC6_centroid from /mnt/d/SourceData/NetworkTests/pc6_centroid.csv [[/brondata/PC6_centroid]] 2026-08-22 05:48:49[9][.][performance]read /brondata/weg/geometry: 390.6ms n=1323835 (1.00x derived) B=73.23M (0.22x) 187.4MB/s 27 chores 2026-08-22 05:48:49[15][.][performance]oper first_point [[/netwerk/WegenVerbonden/LinkSet/point]]: 0.0ms n=1323835 (1.00x derived) B=10.10M (1.00x) ops=1323835 deferred 27x384K res=10.10M 2026-08-22 05:48:49[4][.][performance]oper last_point [[/netwerk/WegenVerbonden/LinkSet/NextPoint]]: 0.0ms n=1323835 (1.00x derived) B=10.10M (1.00x) ops=1323835 deferred 27x384K res=10.10M 2026-08-22 05:48:49[9][!][storage read]Read isSnelWeg from /mnt/d/SourceData/NetworkTests/roads_nl_be_du.fss [[/brondata/weg/isSnelWeg]] 2026-08-22 05:48:49[9][.][other]CreateAbstrHeapTileFunctor(attribute /brondata/weg/isSnelWeg(27 tiles)) 2026-08-22 05:48:49[9][.][performance]read /brondata/weg/isSnelWeg: 35.9ms n=1323835 (1.00x derived) B=161K (1.00x) 4.4MB/s 27 chores 2026-08-22 05:48:49[4][.][performance]oper union_data [[/netwerk/WegenVerbonden/LinkSet/pointset/point]]: 41.7ms n=2647670 (1.00x derived) B=20.20M (1.00x) ops=2647670 deferred 41x504K res=20.20M argmat=20.20M !regime=eager predicted=deferred 2026-08-22 05:48:49[1][.][performance] read /brondata/PC6_centroid: 434.5ms n=? est B=0 derived 2026-08-22 05:48:49[23][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[9][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[10][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[6][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[24][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[18][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[20][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[17][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[9][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[14][.][performance]oper lookup [[/netwerk/WegenVerbonden/ConnectingLinks/Impedance4Links]]: 0.1ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[14][.][performance]oper lookup [[/netwerk/WegenVerbonden/ConnectingLinks/Impedance2Links]]: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[17][.][performance]oper lookup : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[24][.][performance]oper lookup : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[11][.][performance]oper lookup [[/netwerk/WegenVerbonden/ConnectingLinks/Impedance_gt4Links]]: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[9][.][performance]oper lookup [[/netwerk/WegenVerbonden/ConnectingLinks/Impedance3Links]]: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[20][.][performance]oper convert : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[23][.][performance]oper uint32 : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[11][.][performance]oper convert : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:49[21][.][performance]oper arc_length : DataItem: 8.2ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M !regime=eager predicted=deferred 2026-08-22 05:48:49[12][!][storage read]Read KMH from /mnt/d/SourceData/NetworkTests/roads_nl_be_du.fss [[/brondata/weg/KMH]] 2026-08-22 05:48:49[12][.][other]CreateAbstrHeapTileFunctor(attribute /brondata/weg/KMH(27 tiles)) 2026-08-22 05:48:49[17][.][performance]oper lookup [[/netwerk/WegenVerbonden/LinkSet/length_m]]: 2.2ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M 2026-08-22 05:48:49[25][.][performance]oper div : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.05M 2026-08-22 05:48:49[22][.][performance]oper convert [[/netwerk/WegenVerbonden/LinkSet/length_km]]: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.05M 2026-08-22 05:48:49[15][!][storage read]Read x from /mnt/d/SourceData/NetworkTests/pc6_centroid.csv [[/brondata/PC6_centroid/x]] 2026-08-22 05:48:49[15][.][other]CreateAbstrHeapTileFunctor(attribute /brondata/PC6_centroid/x(7 tiles)) 2026-08-22 05:48:49[4][.][performance]oper unique [[/netwerk/WegenVerbonden/NodeSet]]: 169.2ms meta result, no data cost 2026-08-22 05:48:49[6][.][performance]oper SubItem /Values: 0.0ms n=1012518 (1.00x derived) B=7.72M (1.00x) ops=1012518 deferred 16x512K res=7.72M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[16][.][performance]oper const : DataItem: 0.0ms n=1012518 (1.00x derived) B=3.86M (1.00x) ops=1012518 deferred 16x256K res=3.86M | sched n=1000000 eager assumed !regime=streaming predicted=deferred 2026-08-22 05:48:49[12][.][performance]read /brondata/weg/KMH: 50.3ms n=1323835 (1.00x derived) B=5.05M (1.00x) 100.3MB/s 27 chores 2026-08-22 05:48:49[6][.][performance]oper div : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.05M 2026-08-22 05:48:49[10][.][performance]oper convert [[/netwerk/WegenVerbonden/LinkSet/traveltime_hr]]: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.05M 2026-08-22 05:48:49[19][.][performance]oper mul : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.05M 2026-08-22 05:48:49[22][.][performance]oper convert [[/netwerk/WegenVerbonden/LinkSet/traveltime_s]]: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.05M 2026-08-22 05:48:49[3][.][performance]oper rlookup [[/netwerk/WegenVerbonden/F1]]: 23.1ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M ws=3.86M argmat=10.10M 2026-08-22 05:48:49[16][.][performance]oper rlookup [[/netwerk/WegenVerbonden/F2]]: 24.4ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M ws=3.86M argmat=10.10M 2026-08-22 05:48:49[23][.][performance]oper pcount : DataItem: 27.8ms n=1012518 (1.00x derived) B=3.86M (1.00x) ops=1323835 deferred 16x256K res=3.86M argmat=5.05M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[13][.][performance]oper pcount : DataItem: 37.7ms n=1012518 (1.00x derived) B=3.86M (1.00x) ops=1323835 deferred 16x256K res=3.86M argmat=5.05M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[14][.][performance]oper add [[/netwerk/WegenVerbonden/NodeSet/NrConnectedLinks]]: 0.0ms n=1012518 (1.00x derived) B=3.86M (1.00x) ops=1012518 deferred 16x256K res=3.86M | sched n=1000000 eager assumed 2026-08-22 05:48:49[18][.][performance]oper lookup [[/netwerk/WegenVerbonden/ConnectingLinks/Start]]: 2.7ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=8.91M 2026-08-22 05:48:49[2][.][performance]oper lookup [[/netwerk/WegenVerbonden/ConnectingLinks/End]]: 2.7ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=8.91M 2026-08-22 05:48:49[4][.][performance]oper eq : DataItem: 0.0ms n=1323835 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 27x6K res=161K argmat=5.05M 2026-08-22 05:48:49[23][.][performance]oper le : DataItem: 0.0ms n=1323835 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 27x6K res=161K argmat=5.05M 2026-08-22 05:48:49[20][.][performance]oper eq : DataItem: 0.0ms n=1323835 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 27x6K res=161K argmat=5.05M 2026-08-22 05:48:49[22][.][performance]oper eq : DataItem: 0.0ms n=1323835 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 27x6K res=161K argmat=5.05M 2026-08-22 05:48:49[14][.][performance]oper le : DataItem: 0.0ms n=1323835 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 27x6K res=161K argmat=5.05M 2026-08-22 05:48:49[4][.][performance]oper iif : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=161K 2026-08-22 05:48:49[7][.][performance]oper eq : DataItem: 0.0ms n=1323835 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 27x6K res=161K argmat=5.05M 2026-08-22 05:48:49[17][.][performance]oper iif : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.21M 2026-08-22 05:48:49[24][.][performance]oper iif : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=161K 2026-08-22 05:48:49[6][.][performance]oper iif [[/netwerk/WegenVerbonden/ConnectingLinks/impedance_Start]]: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.21M 2026-08-22 05:48:49[5][.][performance]oper iif : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.21M 2026-08-22 05:48:49[3][.][performance]oper add : DataItem: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=10.10M 2026-08-22 05:48:49[9][.][performance]oper iif [[/netwerk/WegenVerbonden/ConnectingLinks/impedance_End]]: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=5.21M 2026-08-22 05:48:49[2][.][performance]oper add [[/netwerk/WegenVerbonden/traveltime_all_s]]: 0.0ms n=1323835 (1.00x derived) B=5.05M (1.00x) ops=1323835 deferred 27x192K res=5.05M argmat=10.10M 2026-08-22 05:48:49[21][.][performance]oper connected_parts [[/netwerk/WegenVerbonden/networks]]: 120.5ms meta result, no data cost 2026-08-22 05:48:49[13][.][performance]oper SubItem /part_rel: 0.0ms n=1012518 (1.00x derived) B=3.86M (1.00x) ops=1012518 deferred 16x256K res=3.86M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[23][.][performance]oper sum [[/netwerk/WegenVerbonden/networks/nr_nodes]]: 1.0ms n=599 (1.00x derived) B=2K (1.00x) ops=1012518 eager res=2K ws=56K argmat=3.86M | sched n=1000000 eager assumed 2026-08-22 05:48:49[9][.][performance]oper max [[/netwerk/WegenVerbonden/networks/MaxNrNodes]]: 0.8ms n=1 (1.00x derived) B=0 (1.00x) ops=599 eager res=0 ws=192 2026-08-22 05:48:49[2][.][performance]oper rlookup [[/netwerk/WegenVerbonden/networks/main]]: 0.1ms n=1 (1.00x derived) B=0 (1.00x) ops=599 eager res=0 ws=2K 2026-08-22 05:48:49[21][.][performance]oper ne : DataItem: 0.0ms n=1012518 (1.00x derived) B=123K (1.00x) ops=1012518 deferred 16x8K res=123K | sched n=1000000 eager assumed 2026-08-22 05:48:49[4][.][performance]oper select_with_org_rel [[/netwerk/WegenVerbonden/NodesNotConnected]]: 3.2ms meta result, no data cost 2026-08-22 05:48:49[12][.][performance]oper SubItem /org_rel: 0.0ms n=2630 (1.00x derived) B=10K (1.00x) ops=2630 eager res=10K | sched n=1000000 eager assumed 2026-08-22 05:48:49[17][.][performance]oper lookup [[/netwerk/WegenVerbonden/NodesNotConnected/point]]: 4.1ms n=2630 (1.00x derived) B=20K (1.00x) ops=1012518 eager res=20K | sched n=1000000 eager assumed 2026-08-22 05:48:49[5][.][performance]oper rlookup : DataItem: 0.0ms n=2647670 (1.00x derived) B=10.10M (1.00x) ops=2647670 deferred 41x256K res=10.10M ws=10K 2026-08-22 05:48:49[7][.][performance]oper IsNull [[/netwerk/WegenVerbonden/LinkSet/pointset/isVerbonden]]: 0.0ms n=2647670 (1.00x derived) B=323K (1.00x) ops=2647670 deferred 41x8K res=323K argmat=10.10M 2026-08-22 05:48:49[12][.][performance]oper all [[/netwerk/WegenVerbonden/LinkSet/isVerbonden]]: 20.6ms n=1323835 (1.00x derived) B=161K (1.00x) ops=2647670 deferred 27x6K res=161K ws=3.79M argmat=10.42M !regime=eager predicted=deferred 2026-08-22 05:48:49[3][.][performance]oper select_with_org_rel [[/netwerk/WegenVerbonden/resultaat/alle]]: 3.2ms meta result, no data cost 2026-08-22 05:48:49[17][.][performance]oper SubItem /org_rel: 0.0ms n=1321475 (1.00x derived) B=5.04M (1.00x) ops=1321475 deferred 21x256K res=5.04M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[19][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/alle/isSnelWeg]]: 4.9ms n=1321475 (1.00x derived) B=161K (1.00x) ops=1323835 deferred 21x8K res=161K | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[8][.][performance]oper not : DataItem: 0.1ms n=1321475 (1.00x derived) B=161K (1.00x) ops=1321475 deferred 21x8K res=161K | sched n=1000000 eager assumed 2026-08-22 05:48:49[17][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/alle/traveltime_s]]: 18.1ms n=1321475 (1.00x derived) B=5.04M (1.00x) ops=1323835 deferred 21x256K res=5.04M argmat=5.05M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[2][.][performance]oper select_with_org_rel [[/netwerk/WegenVerbonden/resultaat/snelwegen]]: 2.7ms meta result, no data cost 2026-08-22 05:48:49[22][.][performance]oper SubItem /org_rel: 0.0ms n=320620 (1.00x derived) B=1.22M (1.00x) ops=320620 deferred 5x256K res=1.22M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[7][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/snelwegen/traveltime_s]]: 1.8ms n=320620 (1.00x derived) B=1.22M (1.00x) ops=1321475 deferred 5x256K res=1.22M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[9][.][performance]oper select_with_org_rel [[/netwerk/WegenVerbonden/resultaat/geen_snelwegen]]: 5.6ms meta result, no data cost 2026-08-22 05:48:49[23][.][performance]oper SubItem /org_rel: 0.0ms n=1000855 (1.00x derived) B=3.82M (1.00x) ops=1000855 deferred 16x256K res=3.82M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[10][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/geen_snelwegen/traveltime_s]]: 2.0ms n=1000855 (1.00x derived) B=3.82M (1.00x) ops=1321475 deferred 16x256K res=3.82M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[13][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/alle/geometry]]: 56.4ms n=1321475 (1.00x derived) B=73.09M (1.00x) ops=1323835 deferred 21x3.62M res=73.09M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[10][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/geen_snelwegen/geometry]]: 38.0ms n=1000855 (1.00x derived) B=55.36M (1.00x) ops=1321475 deferred 16x3.62M res=55.36M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[20][.][performance]oper lookup [[/netwerk/WegenVerbonden/resultaat/snelwegen/geometry]]: 40.4ms n=320620 (1.00x derived) B=21.71M (1.22x) ops=1321475 deferred 5x3.62M res=17.73M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:49[15][.][performance]read /brondata/PC6_centroid/x: 573.4ms n=458108 (1.00x derived) B=9.61M (0.55x) 16.8MB/s 7 chores 2026-08-22 05:48:49[15][.][performance]oper float32 : DataItem: 0.0ms n=458108 (1.00x derived) B=1.75M (1.00x) ops=458108 deferred 7x256K res=1.75M 2026-08-22 05:48:49[16][!][storage read]Read y from /mnt/d/SourceData/NetworkTests/pc6_centroid.csv [[/brondata/PC6_centroid/y]] 2026-08-22 05:48:49[16][.][other]CreateAbstrHeapTileFunctor(attribute /brondata/PC6_centroid/y(7 tiles)) 2026-08-22 05:48:50[16][.][performance]read /brondata/PC6_centroid/y: 499.4ms n=458108 (1.00x derived) B=9.61M (0.55x) 19.2MB/s 7 chores 2026-08-22 05:48:50[9][.][performance]oper float32 : DataItem: 0.0ms n=458108 (1.00x derived) B=1.75M (1.00x) ops=458108 deferred 7x256K res=1.75M 2026-08-22 05:48:50[17][.][performance]oper point_xy [[/brondata/PC6_centroid/geometry]]: 0.0ms n=458108 (1.00x derived) B=3.50M (1.00x) ops=458108 deferred 7x512K res=3.50M argmat=3.50M 2026-08-22 05:48:54[17][.][performance]oper connect [[/netwerk/WegenMetLokaties/PC6/SubWegenMetVerbondenLocaties]]: 3726.8ms meta result, no data cost 2026-08-22 05:48:54[9][.][performance]oper union_unit [[/netwerk/WegenMetLokaties/PC6/WegenMetVerbondenLocaties]]: 0.0ms meta result, no data cost 2026-08-22 05:48:54[6][.][performance]oper SubItem /arc_rel: 0.0ms n=1905636 (1.00x derived) B=7.27M (1.00x) ops=1905636 deferred 30x256K res=7.27M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[8][.][performance]oper SubItem /geometry: 0.0ms n=1905636 (1.00x derived) B=29.08M (1.00x) ops=1905636 deferred 30x1.00M res=29.08M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[9][.][performance]oper union_unit [[/netwerk/WegenMetLokaties/PC6/LinkSet/pointset]]: 0.0ms meta result, no data cost 2026-08-22 05:48:54[6][.][performance]oper lookup [[/netwerk/WegenMetLokaties/PC6/SubWegenMetVerbondenLocaties/traveltime_s]]: 1.0ms n=1905636 (1.00x derived) B=7.27M (1.00x) ops=1905636 deferred 30x256K res=7.27M | sched n=1000000 eager assumed 2026-08-22 05:48:54[23][.][performance]oper union_data [[/netwerk/WegenMetLokaties/PC6/WegenMetVerbondenLocaties/traveltime_s]]: 4.7ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x255K res=8.49M argmat=7.27M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[17][.][performance]oper IsDefined : DataItem: 0.0ms n=2226256 (1.00x derived) B=271K (1.00x) ops=2226256 deferred 34x8K res=271K | sched n=1000000 eager assumed 2026-08-22 05:48:54[8][.][performance]oper union_data [[/netwerk/WegenMetLokaties/PC6/WegenMetVerbondenLocaties/geometry]]: 110.8ms n=2226256 (1.00x derived) B=1.09G (22.07x) ops=2226256 deferred 34x1.49M res=50.79M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[2][.][performance]oper last_point [[/netwerk/WegenMetLokaties/PC6/LinkSet/NextPoint]]: 0.1ms n=2226256 (1.00x derived) B=16.98M (1.00x) ops=2226256 deferred 34x512K res=16.98M | sched n=1000000 eager assumed 2026-08-22 05:48:54[15][.][performance]oper first_point [[/netwerk/WegenMetLokaties/PC6/LinkSet/point]]: 0.0ms n=2226256 (1.00x derived) B=16.98M (1.00x) ops=2226256 deferred 34x512K res=16.98M | sched n=1000000 eager assumed 2026-08-22 05:48:54[8][.][performance]oper arc_length : DataItem: 9.4ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[8][.][performance]oper lookup [[/netwerk/WegenMetLokaties/PC6/LinkSet/length_m]]: 0.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M | sched n=1000000 eager assumed 2026-08-22 05:48:54[16][.][performance]oper div : DataItem: 0.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M argmat=8.49M | sched n=1000000 eager assumed 2026-08-22 05:48:54[16][.][performance]oper convert [[/netwerk/WegenMetLokaties/PC6/LinkSet/length_km]]: 0.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M argmat=8.49M | sched n=1000000 eager assumed 2026-08-22 05:48:54[9][.][performance]oper div : DataItem: 0.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M argmat=8.49M | sched n=1000000 eager assumed 2026-08-22 05:48:54[21][.][performance]oper mul : DataItem: 0.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M argmat=8.49M | sched n=1000000 eager assumed 2026-08-22 05:48:54[17][.][performance]oper iif [[/netwerk/WegenMetLokaties/PC6/LinkSet/traveltime_s]]: 0.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M argmat=8.76M | sched n=1000000 eager assumed 2026-08-22 05:48:54[15][.][performance]oper union_data [[/netwerk/WegenMetLokaties/PC6/LinkSet/pointset/point]]: 38.6ms n=4452512 (1.00x derived) B=33.97M (1.00x) ops=4452512 deferred 68x511K res=33.97M argmat=33.97M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[20][.][performance]oper unique [[/netwerk/WegenMetLokaties/PC6/NodeSet]]: 265.5ms meta result, no data cost 2026-08-22 05:48:54[16][.][performance]oper SubItem /Values: 0.0ms n=1907348 (1.00x derived) B=14.55M (1.00x) ops=1907348 deferred 30x512K res=14.55M | sched n=1000000 eager assumed !regime=eager predicted=deferred 2026-08-22 05:48:54[4][.][performance]oper rlookup [[/netwerk/WegenMetLokaties/PC6/F2]]: 39.8ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M ws=7.28M argmat=16.98M | sched n=1000000 eager assumed 2026-08-22 05:48:54[16][.][performance]oper rlookup [[/netwerk/WegenMetLokaties/PC6/nr_orgNode]]: 43.2ms n=458108 (1.00x derived) B=1.75M (1.00x) ops=1907348 deferred 7x256K res=1.75M ws=7.28M argmat=3.50M !regime=eager predicted=deferred 2026-08-22 05:48:54[11][.][performance]oper rlookup [[/netwerk/WegenMetLokaties/PC6/F1]]: 46.0ms n=2226256 (1.00x derived) B=8.49M (1.00x) ops=2226256 deferred 34x256K res=8.49M ws=7.28M argmat=16.98M | sched n=1000000 eager assumed 2026-08-22 05:48:55[17][!][progress][[/OD/PC6_sparse]] impedance_matrix Counting all 458,108 sources: resulted in 7,812,324 od-pairs 2026-08-22 05:48:56[17][!][progress][[/OD/PC6_sparse]] impedance_matrix Filling all 458,108 sources: resulted in 7,812,324 od-pairs 2026-08-22 05:48:56[17][.][performance]oper impedance_matrix [[/OD/PC6_sparse]]: 1493.2ms meta result, no data cost 2026-08-22 05:48:56[15][.][performance]oper NrOfRows : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[1][.][performance] oper string [[/results/t102_network_od_pc6_sparse/m/value_str]]: 0.1ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[12][.][performance]oper replace : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[23][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[23][.][performance]oper add [[/results/t102_network_od_pc6_sparse/m/result]]: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[23][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[19][.][performance]oper add : DataItem: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[1][.][performance] oper add [[/results/t102_network_od_pc6_sparse/result_json]]: 0.0ms n=1 (1.00x derived) B=0 (1.00x) ops=1 eager res=0 2026-08-22 05:48:56[1][!][storage write] Writing to /mnt/c/LocalData/GeoDMS_Test_Results/20_16_0_l/result/t102_network_od_pc6_sparse.result.json [[/results/t102_network_od_pc6_sparse/result_json]] 2026-08-22 05:48:56[1][.][progress]} Updating::[[/results/t102_network_od_pc6_sparse/result_json]] (55.076076 secs) 2026-08-22 05:48:56[1][!][memory]vmcalls: decommit 0x/0ms, recommit 0x/0ms, drained 0x = 0[MB]/0ms (0[KB]/call over 0 sweeps, worst sweep 0ms); peak concurrent 0 2026-08-22 05:48:56[1][!][memory]alloc histogram (log2 buckets, >=4K): 4K:11203 8K:5015 16K:1979 32K:1023 64K:572 128K:928 256K:1471 512K:413 1M:364 2M:122 4M:187 8M:15 16M:11 32M:3 2026-08-22 05:48:56[1][!][memory]Highest Reserved in Blocks 197[MB]; Highest allocated: 34[MB]; Highest freed: 138[MB]; Highest uncommitted: 23[MB]; Highest CommitCharge: 619[MB]; PeakLiveLarge: 1046[MB]; PeakFreeStack: 169[MB]; residual live: req 0[MB] vs fs 0[MB] 2026-08-22 05:48:56[1][!][progress]@@@@@ Logging ended for /mnt/c/LocalData/GeoDMS_Test_Results/20_16_0_l/log/t102_network_od_pc6_sparse.txt at 2026-08-22 05:48:56