Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001435/0.001435, allocations: 105.5 kB / 16.4 MB, free: 6.5 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.3/1.3, allocations: 222.9 MB / 242.1 MB, free: 15.39 MB / 206.1 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001377/0.001377, allocations: 191 kB / 294.4 MB, free: 12.69 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo): time 0.4023/0.4023, allocations: 78.53 MB / 425.1 MB, free: 11.21 MB / 302.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.0.1/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: "" <> buildModelFMU(OpenIPSL.Tests.ThreePhase.IEEE13,fileNamePrefix="OpenIPSL_OpenIPSL_Tests_ThreePhase_IEEE13",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(OpenIPSL.Tests.ThreePhase.IEEE13,fileNamePrefix="OpenIPSL_OpenIPSL_Tests_ThreePhase_IEEE13",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002161/0.002161, allocations: 79.88 kB / 0.5616 GB, free: 18.89 MB / 382.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.113/0.1151, allocations: 68.18 MB / 0.6282 GB, free: 14.69 MB / 446.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.ThreePhase.IEEE13): time 0.01536/0.1305, allocations: 16.25 MB / 0.6441 GB, free: 14.35 MB / 462.1 MB
Notification: Performance of NFInst.instExpressions: time 0.009836/0.1404, allocations: 5.302 MB / 0.6493 GB, free: 9.035 MB / 462.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001703/0.1421, allocations: 35.94 kB / 0.6493 GB, free: 9 MB / 462.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001321/0.1435, allocations: 0.6001 MB / 0.6499 GB, free: 8.395 MB / 462.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.009695/0.1532, allocations: 6.243 MB / 0.656 GB, free: 2.133 MB / 462.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001527/0.1547, allocations: 0.6511 MB / 0.6566 GB, free: 1.48 MB / 462.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00658/0.1613, allocations: 5.589 MB / 0.6621 GB, free: 11.87 MB / 478.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001881/0.1632, allocations: 1.413 MB / 0.6635 GB, free: 10.4 MB / 478.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003792/0.167, allocations: 2.612 MB / 0.666 GB, free: 7.781 MB / 478.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.006798/0.1738, allocations: 5.924 MB / 0.6718 GB, free: 1.844 MB / 478.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001739/0.1756, allocations: 0.7891 MB / 0.6726 GB, free: 1.055 MB / 478.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.01383/0.1894, allocations: 12.24 MB / 0.6845 GB, free: 4.797 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003322/0.1928, allocations: 3.062 MB / 0.6875 GB, free: 1.727 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.2413/0.434, allocations: 4.068 MB / 0.6915 GB, free: 20.55 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02127/0.4553, allocations: 17.05 MB / 0.7081 GB, free: 19.71 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.561e-06/0.4554, allocations: 3.75 kB / 0.7081 GB, free: 19.71 MB / 494.1 MB
Notification: Performance of FrontEnd: time 1.814e-06/0.4554, allocations: 1.219 kB / 0.7081 GB, free: 19.71 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0003199/0.4557, allocations: 0 / 0.7081 GB, free: 19.71 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1006
 * Number of variables: 1006
Notification: Performance of Generate backend data structure: time 0.03171/0.4874, allocations: 11.25 MB / 0.7191 GB, free: 15.13 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.305e-05/0.4875, allocations: 9.125 kB / 0.7191 GB, free: 15.13 MB / 494.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0009763/0.4885, allocations: 0.5009 MB / 0.7196 GB, free: 14.77 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00214/0.4906, allocations: 0.8657 MB / 0.7205 GB, free: 14.29 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009332/0.5, allocations: 6.147 MB / 0.7265 GB, free: 11.04 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002536/0.5002, allocations: 399.6 kB / 0.7269 GB, free: 10.75 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000868/0.5011, allocations: 292.1 kB / 0.7271 GB, free: 10.66 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.009333/0.5105, allocations: 7.921 MB / 0.7349 GB, free: 6.879 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.992e-05/0.5105, allocations: 0 / 0.7349 GB, free: 6.879 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005088/0.511, allocations: 121.5 kB / 0.735 GB, free: 6.793 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0003821/0.5114, allocations: 0.5123 MB / 0.7355 GB, free: 6.676 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02078/0.5322, allocations: 14.3 MB / 0.7494 GB, free: 11.21 MB / 0.4981 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06364/0.5959, allocations: 30.52 MB / 0.7793 GB, free: 11.98 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01112/0.607, allocations: 7.872 MB / 0.7869 GB, free: 3.906 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007393/0.6145, allocations: 4.941 MB / 0.7918 GB, free: 14.96 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001729/0.6162, allocations: 1.985 MB / 0.7937 GB, free: 12.53 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.108e-05/0.6163, allocations: 73.52 kB / 0.7938 GB, free: 12.45 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=391): time 6.732e-06/0.6163, allocations: 0 / 0.7938 GB, free: 12.45 MB / 0.545 GB
Notification: Performance of matching and sorting (n=391): time 0.03513/0.6514, allocations: 17.62 MB / 0.811 GB, free: 10.81 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.723e-05/0.6516, allocations: 94.36 kB / 0.8111 GB, free: 10.71 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01749/0.6691, allocations: 9.255 MB / 0.8201 GB, free: 1.488 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.001345/0.6704, allocations: 81.7 kB / 0.8202 GB, free: 1.402 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003346/0.6738, allocations: 3.406 MB / 0.8235 GB, free: 14.05 MB / 0.5762 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002431/0.6762, allocations: 1.386 MB / 0.8249 GB, free: 12.68 MB / 0.5762 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001772/0.678, allocations: 0.6535 MB / 0.8255 GB, free: 12.02 MB / 0.5762 GB
Notification: Performance of setup shared object (initialization): time 0.0001212/0.6781, allocations: 301.1 kB / 0.8258 GB, free: 11.73 MB / 0.5762 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005924/0.6841, allocations: 4.035 MB / 0.8297 GB, free: 7.691 MB / 0.5762 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006821/0.6909, allocations: 4.719 MB / 0.8343 GB, free: 2.73 MB / 0.5762 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01438/0.7053, allocations: 9.077 MB / 0.8432 GB, free: 9.461 MB / 0.5919 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.935e-05/0.7053, allocations: 7.938 kB / 0.8432 GB, free: 9.453 MB / 0.5919 GB
Notification: Performance of matching and sorting (n=487) (initialization): time 0.02464/0.73, allocations: 14.01 MB / 0.8569 GB, free: 11.52 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 3.701e-05/0.7301, allocations: 19.75 kB / 0.8569 GB, free: 11.51 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.893e-05/0.7301, allocations: 16 kB / 0.8569 GB, free: 11.49 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.1001/0.8302, allocations: 40.36 MB / 0.8963 GB, free: 2.902 MB / 0.6387 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001276/0.8315, allocations: 307.5 kB / 0.8966 GB, free: 2.625 MB / 0.6387 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03592/0.8675, allocations: 16.7 MB / 0.9129 GB, free: 1.887 MB / 0.6544 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006478/0.874, allocations: 0.5549 MB / 0.9135 GB, free: 1.332 MB / 0.6544 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006392/0.8746, allocations: 132 kB / 0.9136 GB, free: 1.203 MB / 0.6544 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 81
 * Number of states: 0 ()
 * Number of discrete variables: 3 (breaker3.Open,breaker2.Open,breaker.Open)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (170):
 * Single equations (assignments): 169
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(126,187)}
Notification: Performance of prepare postOptimizeDAE: time 0.00399/0.8786, allocations: 1.763 MB / 0.9153 GB, free: 15.45 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002904/0.8816, allocations: 0.7218 MB / 0.916 GB, free: 14.73 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02863/0.9102, allocations: 15.64 MB / 0.9313 GB, free: 15.09 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.566e-05/0.9103, allocations: 67.98 kB / 0.9314 GB, free: 15.02 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.827e-06/0.9103, allocations: 3.938 kB / 0.9314 GB, free: 15.02 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.41e-05/0.9104, allocations: 19.92 kB / 0.9314 GB, free: 15 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.2747/1.185, allocations: 23.52 MB / 0.9544 GB, free: 248.8 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.222e-05/1.185, allocations: 1.531 kB / 0.9544 GB, free: 248.8 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006546/1.186, allocations: 129.4 kB / 0.9545 GB, free: 248.8 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.07842/1.264, allocations: 26.81 MB / 0.9807 GB, free: 235.8 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001414/1.266, allocations: 60 kB / 0.9807 GB, free: 235.8 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02876/1.294, allocations: 16.74 MB / 0.9971 GB, free: 219.2 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.98e-06/1.295, allocations: 6.75 kB / 0.9971 GB, free: 219.2 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01888/1.313, allocations: 14.04 MB / 1.011 GB, free: 205.5 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003186/1.317, allocations: 1.301 MB / 1.012 GB, free: 204.2 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002108/1.319, allocations: 60.86 kB / 1.012 GB, free: 204.1 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004832/1.324, allocations: 300.8 kB / 1.012 GB, free: 203.8 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008646/1.324, allocations: 190.6 kB / 1.013 GB, free: 203.7 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003614/1.325, allocations: 92.84 kB / 1.013 GB, free: 203.6 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.009041/1.334, allocations: 6.574 MB / 1.019 GB, free: 197 MB / 0.6856 GB
Notification: Performance of sort global known variables: time 5.01e-07/1.334, allocations: 0 / 1.019 GB, free: 197 MB / 0.6856 GB
Notification: Performance of remove unused functions: time 0.01067/1.345, allocations: 2.333 MB / 1.021 GB, free: 194.8 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 0 ()
 * Number of discrete variables: 3 (breaker.Open,breaker2.Open,breaker3.Open)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (74):
 * Single equations (assignments): 73
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(128,185)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.02619/1.371, allocations: 15.04 MB / 1.036 GB, free: 179.7 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.02247/1.393, allocations: 12.12 MB / 1.048 GB, free: 167.6 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 4.598e-06/1.393, allocations: 0 / 1.048 GB, free: 167.6 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.01619/1.41, allocations: 7.51 MB / 1.055 GB, free: 160.1 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004983/1.415, allocations: 0.5296 MB / 1.056 GB, free: 159.6 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1215/1.536, allocations: 81.38 MB / 1.135 GB, free: 77.5 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002353/1.539, allocations: 3.681 MB / 1.139 GB, free: 73.79 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.002151/1.541, allocations: 0.8912 MB / 1.14 GB, free: 72.9 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.004477/1.545, allocations: 3.159 MB / 1.143 GB, free: 69.74 MB / 0.6856 GB
Notification: Performance of SimCode: time 9.91e-07/1.545, allocations: 4 kB / 1.143 GB, free: 69.73 MB / 0.6856 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.449/1.994, allocations: 194 MB / 1.332 GB, free: 133.8 MB / 0.6856 GB
Notification: Performance of buildModelFMU: Generate platform static: time 28.1/30.1, allocations: 8.469 kB / 1.332 GB, free: 133.8 MB / 0.6856 GB
"
[Timeout remaining time 630]
(rm -f OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13.sim & python3 -m fmpy simulate  --output-file OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13_res.csv --start-time 0 --stop-time 10 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.001 OpenIPSL_OpenIPSL_Tests_ThreePhase_IEEE13.fmu > OpenIPSL_OpenIPSL.Tests.ThreePhase.IEEE13.pipe 2>&1) [Timeout 52.5]
[Calling sys.exit(0), Time elapsed: 53.97170953697059]
Failed to read output from testmodel.py, exit status != 0:
30.270719218999147 30.279545144 29.822443912
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f43292b2020>