Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001288/0.001288, allocations: 79.28 kB / 19.18 MB, free: 2.516 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.398/1.398, allocations: 177.1 MB / 199.4 MB, free: 8.453 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001585/0.001585, allocations: 159.6 kB / 256 MB, free: 4.383 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo): time 0.5812/0.5812, allocations: 80.35 MB / 392.7 MB, free: 13.63 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.2.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL master/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(OpenIPSL.Tests.ThreePhase.IEEE13,tolerance=1e-06,outputFormat="empty",numberOfIntervals=10000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13")
translateModel(OpenIPSL.Tests.ThreePhase.IEEE13,tolerance=1e-06,outputFormat="empty",numberOfIntervals=10000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.172e-06/1.172e-06, allocations: 0 / 0.5554 GB, free: 3.809 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.891e-05/2.008e-05, allocations: 2.281 kB / 0.5554 GB, free: 3.805 MB / 414.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.ThreePhase.IEEE13): time 0.01377/0.01379, allocations: 13.7 MB / 0.5688 GB, free: 6.527 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01113/0.02492, allocations: 5.084 MB / 0.5738 GB, free: 1.43 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002679/0.0276, allocations: 35.94 kB / 0.5738 GB, free: 1.395 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00175/0.02935, allocations: 0.6156 MB / 0.5744 GB, free: 0.7734 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01223/0.04158, allocations: 6.452 MB / 0.5807 GB, free: 10.3 MB / 446.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002027/0.04361, allocations: 0.6392 MB / 0.5813 GB, free: 9.66 MB / 446.1 MB
Notification: Performance of NFFlatten.flatten: time 0.007951/0.05156, allocations: 5.725 MB / 0.5869 GB, free: 3.914 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001811/0.05337, allocations: 0.9756 MB / 0.5879 GB, free: 2.883 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006324/0.05969, allocations: 3.535 MB / 0.5913 GB, free: 15.34 MB / 462.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.007867/0.06756, allocations: 5.685 MB / 0.5969 GB, free: 9.645 MB / 462.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002731/0.07029, allocations: 0.7891 MB / 0.5976 GB, free: 8.855 MB / 462.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.009722/0.08001, allocations: 6.034 MB / 0.6035 GB, free: 2.812 MB / 462.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.004336/0.08435, allocations: 3.085 MB / 0.6066 GB, free: 15.72 MB / 478.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004735/0.08908, allocations: 2.625 MB / 0.6091 GB, free: 13.09 MB / 478.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02047/0.1096, allocations: 14.63 MB / 0.6234 GB, free: 14.42 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.992e-06/0.1096, allocations: 4 kB / 0.6234 GB, free: 14.41 MB / 494.1 MB
Notification: Performance of FrontEnd: time 1.683e-06/0.1096, allocations: 0 / 0.6234 GB, free: 14.41 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 0.0002614/0.1098, allocations: 0 / 0.6234 GB, free: 14.41 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: 1008
 * Number of variables: 1008
Notification: Performance of Generate backend data structure: time 0.02663/0.1365, allocations: 10.46 MB / 0.6336 GB, free: 3.867 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.782e-05/0.1365, allocations: 8.031 kB / 0.6336 GB, free: 3.859 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0018/0.1383, allocations: 0.8197 MB / 0.6344 GB, free: 3.035 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009634/0.1479, allocations: 5.405 MB / 0.6397 GB, free: 13.57 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004948/0.1484, allocations: 0.5419 MB / 0.6402 GB, free: 12.95 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008245/0.1492, allocations: 292.3 kB / 0.6405 GB, free: 12.67 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01255/0.1618, allocations: 7.934 MB / 0.6483 GB, free: 4.707 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.125e-05/0.1619, allocations: 0 / 0.6483 GB, free: 4.707 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005536/0.1624, allocations: 116 kB / 0.6484 GB, free: 4.594 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005889/0.163, allocations: 0.5112 MB / 0.6489 GB, free: 4.082 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02479/0.1878, allocations: 14.23 MB / 0.6628 GB, free: 5.844 MB / 0.5138 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.05706/0.2448, allocations: 23.68 MB / 0.6859 GB, free: 13.95 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01081/0.2556, allocations: 6.071 MB / 0.6918 GB, free: 7.93 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007349/0.263, allocations: 4.344 MB / 0.6961 GB, free: 3.578 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0008629/0.2639, allocations: 110.9 kB / 0.6962 GB, free: 3.469 MB / 0.545 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 7.582e-05/0.2639, allocations: 77.52 kB / 0.6962 GB, free: 3.383 MB / 0.545 GB
Notification: Performance of pre-optimization done (n=393): time 8.476e-06/0.2639, allocations: 0 / 0.6962 GB, free: 3.383 MB / 0.545 GB
Notification: Performance of matching and sorting (n=393): time 0.3062/0.5702, allocations: 18.52 MB / 0.7143 GB, free: 123.7 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.416e-05/0.5702, allocations: 92.16 kB / 0.7144 GB, free: 123.6 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.009831/0.58, allocations: 8.457 MB / 0.7227 GB, free: 119.8 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.001074/0.5811, allocations: 85.7 kB / 0.7228 GB, free: 119.8 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001684/0.5828, allocations: 3.094 MB / 0.7258 GB, free: 117 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00152/0.5843, allocations: 1.398 MB / 0.7271 GB, free: 115.8 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001689/0.586, allocations: 0.9485 MB / 0.7281 GB, free: 114.8 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 3.823e-05/0.586, allocations: 305.1 kB / 0.7284 GB, free: 114.5 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004911/0.591, allocations: 4.534 MB / 0.7328 GB, free: 110 MB / 0.545 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005335/0.5963, allocations: 5.308 MB / 0.738 GB, free: 104.4 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0127/0.609, allocations: 9.743 MB / 0.7475 GB, free: 94.38 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.569e-05/0.609, allocations: 8 kB / 0.7475 GB, free: 94.38 MB / 0.545 GB
Notification: Performance of matching and sorting (n=633) (initialization): time 0.01996/0.629, allocations: 14.68 MB / 0.7618 GB, free: 79.61 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 3.831e-05/0.629, allocations: 18.64 kB / 0.7618 GB, free: 79.59 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.743e-05/0.6291, allocations: 20 kB / 0.7619 GB, free: 79.57 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.08906/0.7181, allocations: 38.28 MB / 0.7992 GB, free: 41.11 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001834/0.7199, allocations: 0.5226 MB / 0.7998 GB, free: 40.59 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02876/0.7487, allocations: 16.37 MB / 0.8157 GB, free: 24.16 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006254/0.755, allocations: 0.5412 MB / 0.8163 GB, free: 23.7 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007182/0.7557, allocations: 164 kB / 0.8164 GB, free: 23.54 MB / 0.545 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 (316):
 * Single equations (assignments): 315
 * 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
   {(112,199)}
Notification: Performance of prepare postOptimizeDAE: time 0.003292/0.759, allocations: 1.753 MB / 0.8181 GB, free: 21.79 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001835/0.7608, allocations: 0.675 MB / 0.8188 GB, free: 21.11 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01987/0.7807, allocations: 14.02 MB / 0.8325 GB, free: 7.047 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.238e-05/0.7807, allocations: 63.98 kB / 0.8326 GB, free: 6.984 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.203e-06/0.7808, allocations: 0 / 0.8326 GB, free: 6.984 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.799e-05/0.7808, allocations: 19.92 kB / 0.8326 GB, free: 6.965 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.06176/0.8426, allocations: 25.28 MB / 0.8573 GB, free: 13.72 MB / 0.5763 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.82e-05/0.8426, allocations: 0 / 0.8573 GB, free: 13.72 MB / 0.5763 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007949/0.8434, allocations: 131.7 kB / 0.8574 GB, free: 13.59 MB / 0.5763 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.095/0.9384, allocations: 25.59 MB / 0.8824 GB, free: 3.809 MB / 0.5919 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.00147/0.9399, allocations: 71.66 kB / 0.8824 GB, free: 3.742 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03919/0.9791, allocations: 16.3 MB / 0.8984 GB, free: 3.375 MB / 0.6075 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.843e-06/0.9791, allocations: 3.938 kB / 0.8984 GB, free: 3.371 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.04737/1.026, allocations: 18.5 MB / 0.9164 GB, free: 0.8047 MB / 0.6231 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002933/1.029, allocations: 1.013 MB / 0.9174 GB, free: 15.84 MB / 0.6388 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002551/1.032, allocations: 60 kB / 0.9175 GB, free: 15.78 MB / 0.6388 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00605/1.038, allocations: 271.6 kB / 0.9177 GB, free: 15.52 MB / 0.6388 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001596/1.04, allocations: 194.5 kB / 0.9179 GB, free: 15.32 MB / 0.6388 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0007342/1.04, allocations: 95.03 kB / 0.918 GB, free: 15.23 MB / 0.6388 GB
Notification: Performance of sorting global known variables: time 0.01063/1.051, allocations: 5.85 MB / 0.9237 GB, free: 9.406 MB / 0.6388 GB
Notification: Performance of sort global known variables: time 1.21e-07/1.051, allocations: 4 kB / 0.9237 GB, free: 9.402 MB / 0.6388 GB
Notification: Performance of remove unused functions: time 0.009054/1.06, allocations: 2.406 MB / 0.9261 GB, free: 7.125 MB / 0.6388 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 (76):
 * Single equations (assignments): 75
 * 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
   {(112,199)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.00388/1.064, allocations: 0.5657 MB / 0.9266 GB, free: 6.637 MB / 0.6388 GB
Notification: Performance of simCode: created initialization part: time 0.0277/1.092, allocations: 11.69 MB / 0.9381 GB, free: 10.93 MB / 0.6544 GB
Notification: Performance of simCode: created event and clocks part: time 5.33e-06/1.092, allocations: 0 / 0.9381 GB, free: 10.93 MB / 0.6544 GB
Notification: Performance of simCode: created simulation system equations: time 0.01998/1.112, allocations: 6.458 MB / 0.9444 GB, free: 4.473 MB / 0.6544 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00463/1.116, allocations: 0.4899 MB / 0.9448 GB, free: 3.984 MB / 0.6544 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.269/1.385, allocations: 15.32 MB / 0.9598 GB, free: 229.4 MB / 0.6544 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003688/1.389, allocations: 3.186 MB / 0.9629 GB, free: 229 MB / 0.6544 GB
Notification: Performance of simCode: alias equations: time 0.001558/1.39, allocations: 0.7821 MB / 0.9637 GB, free: 228.9 MB / 0.6544 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0009095/1.391, allocations: 269.3 kB / 0.9639 GB, free: 228.9 MB / 0.6544 GB
Notification: Performance of SimCode: time 1.102e-06/1.391, allocations: 0 / 0.9639 GB, free: 228.9 MB / 0.6544 GB
Notification: Performance of Templates: time 0.1475/1.539, allocations: 125.4 MB / 1.086 GB, free: 111.8 MB / 0.6544 GB
"
[Timeout remaining time 658]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.sim & ./OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.ThreePhase.IEEE13.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 10.626025270670652]
Failed to read output from testmodel.py, exit status != 0:
1.5472638197243214 1.562705918 1.4489463360000001
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f20c94c51e0>