Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.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.001301/0.001301, allocations: 112.5 kB / 18.9 MB, free: 2.906 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.4/1.4, allocations: 225.6 MB / 247.6 MB, free: 13.54 MB / 206.1 MB
"
[Timeout remaining time 178]
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.001455/0.001455, allocations: 196.8 kB / 302.9 MB, free: 8.797 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/package.mo): time 0.549/0.549, allocations: 99.66 MB / 457.8 MB, free: 4.594 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0-dev (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0-master/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: translateModel(OpenIPSL.Tests.Solar.PowerFactory.PVD1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1")
translateModel(OpenIPSL.Tests.Solar.PowerFactory.PVD1,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2000,variableFilter="",fileNamePrefix="OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.002484/0.002484, allocations: 83.86 kB / 0.6149 GB, free: 4.5 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1486/0.1511, allocations: 76.95 MB / 0.6901 GB, free: 7.395 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Solar.PowerFactory.PVD1): time 0.006523/0.1577, allocations: 4.48 MB / 0.6944 GB, free: 2.891 MB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.002962/0.1606, allocations: 1.489 MB / 0.6959 GB, free: 1.395 MB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0003741/0.161, allocations: 11.94 kB / 0.6959 GB, free: 1.383 MB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0003961/0.1614, allocations: 114.7 kB / 0.696 GB, free: 1.27 MB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0004472/0.1618, allocations: 190.4 kB / 0.6962 GB, free: 1.082 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001127/0.163, allocations: 0.5969 MB / 0.6968 GB, free: 496 kB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.2705/0.4335, allocations: 1.252 MB / 0.698 GB, free: 13.27 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0005611/0.4341, allocations: 316.9 kB / 0.6983 GB, free: 13.2 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0005139/0.4346, allocations: 473.6 kB / 0.6987 GB, free: 13.15 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0004373/0.435, allocations: 0.4893 MB / 0.6992 GB, free: 13.04 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001929/0.4352, allocations: 162.1 kB / 0.6994 GB, free: 13.04 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0001839/0.4354, allocations: 164.4 kB / 0.6995 GB, free: 13.04 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0001588/0.4355, allocations: 295.9 kB / 0.6998 GB, free: 13.01 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0003183/0.4359, allocations: 0.5177 MB / 0.7003 GB, free: 12.87 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.001422/0.4373, allocations: 1.761 MB / 0.702 GB, free: 12.59 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.139e-06/0.4373, allocations: 1.25 kB / 0.702 GB, free: 12.59 MB / 494.1 MB
Notification: Performance of FrontEnd: time 1.583e-06/0.4373, allocations: 2.25 kB / 0.702 GB, free: 12.59 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 2.204e-05/0.4373, allocations: 0 / 0.702 GB, free: 12.59 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: 157
 * Number of variables: 157
Notification: Performance of Generate backend data structure: time 0.002993/0.4403, allocations: 1.63 MB / 0.7036 GB, free: 11.64 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.907e-05/0.4403, allocations: 12.61 kB / 0.7036 GB, free: 11.64 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.000175/0.4405, allocations: 106.1 kB / 0.7037 GB, free: 11.59 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0008086/0.4413, allocations: 0.6523 MB / 0.7044 GB, free: 11.12 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002634/0.4416, allocations: 221.7 kB / 0.7046 GB, free: 10.93 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001195/0.4417, allocations: 67.81 kB / 0.7047 GB, free: 10.91 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001962/0.4437, allocations: 0.9693 MB / 0.7056 GB, free: 10.5 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.968e-05/0.4437, allocations: 6.703 kB / 0.7056 GB, free: 10.5 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.619e-05/0.4438, allocations: 26.23 kB / 0.7056 GB, free: 10.48 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.028e-05/0.4438, allocations: 14.34 kB / 0.7057 GB, free: 10.48 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002162/0.4459, allocations: 0.908 MB / 0.7065 GB, free: 10.19 MB / 494.1 MB
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.004743/0.4507, allocations: 3.173 MB / 0.7096 GB, free: 8.379 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.002695/0.4534, allocations: 1.073 MB / 0.7107 GB, free: 7.996 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001073/0.4545, allocations: 463.8 kB / 0.7111 GB, free: 7.832 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 4.477e-05/0.4545, allocations: 12.47 kB / 0.7111 GB, free: 7.832 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.00134/0.4558, allocations: 0.6086 MB / 0.7117 GB, free: 7.613 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=64): time 3.246e-06/0.4558, allocations: 2.969 kB / 0.7117 GB, free: 7.613 MB / 494.1 MB
Notification: Performance of matching and sorting (n=70): time 0.008881/0.4647, allocations: 3.352 MB / 0.715 GB, free: 6.219 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.729e-05/0.4648, allocations: 163.2 kB / 0.7152 GB, free: 6.031 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007768/0.4655, allocations: 0.6093 MB / 0.7158 GB, free: 5.75 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 8.868e-05/0.4656, allocations: 37.7 kB / 0.7158 GB, free: 5.711 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.0002184/0.4659, allocations: 368.8 kB / 0.7161 GB, free: 5.418 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.000175/0.466, allocations: 246.5 kB / 0.7164 GB, free: 5.227 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002583/0.4663, allocations: 131.4 kB / 0.7165 GB, free: 5.164 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001007/0.4664, allocations: 387.8 kB / 0.7169 GB, free: 4.816 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001238/0.4676, allocations: 489.1 kB / 0.7173 GB, free: 4.648 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001275/0.4689, allocations: 0.5881 MB / 0.7179 GB, free: 4.379 MB / 494.1 MB
Error: Initialization problem is structural singular. Please, check the initial conditions.
Notification: Performance of analyzeInitialSystem (initialization): time 0.008149/0.477, allocations: 2.393 MB / 0.7203 GB, free: 3.602 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.907e-06/0.4771, allocations: 4.594 kB / 0.7203 GB, free: 3.598 MB / 494.1 MB
Notification: Performance of matching and sorting (n=84) (initialization): time 0.003115/0.4802, allocations: 1.292 MB / 0.7215 GB, free: 3.137 MB / 494.1 MB
Notification: Performance of prepare postOptimizeDAE: time 1.833e-05/0.4802, allocations: 15.12 kB / 0.7215 GB, free: 3.129 MB / 494.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.634e-06/0.4802, allocations: 2.469 kB / 0.7215 GB, free: 3.129 MB / 494.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003563/0.4805, allocations: 120.6 kB / 0.7217 GB, free: 3.094 MB / 494.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009801/0.4815, allocations: 212.3 kB / 0.7219 GB, free: 3.086 MB / 494.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002453/0.484, allocations: 2.323 MB / 0.7241 GB, free: 1.184 MB / 494.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009266/0.4849, allocations: 108.3 kB / 0.7242 GB, free: 1.168 MB / 494.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.587e-05/0.485, allocations: 28.38 kB / 0.7243 GB, free: 1.164 MB / 494.1 MB
Warning: Assuming fixed start value for the following 1 variables:
         plantPVD1.staVmea.local_df_internal:VARIABLE(flow=false fixed = true protected = true )  \"Helping variable/connector\" type: Real
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization system:
         voltage.y = elmVac.v_0.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001235/0.4862, allocations: 0.5742 MB / 0.7248 GB, free: 0.8828 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0009705/0.4872, allocations: 0.51 MB / 0.7253 GB, free: 0.6367 MB / 494.1 MB
Error: Initialization problem is structural singular. Please, check the initial conditions.
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.006848/0.494, allocations: 2.199 MB / 0.7275 GB, free: 15.61 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.435e-06/0.494, allocations: 1.312 kB / 0.7275 GB, free: 15.61 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=84) (initialization_lambda0): time 0.002381/0.4964, allocations: 1.007 MB / 0.7284 GB, free: 15.11 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 1.216e-05/0.4964, allocations: 5.656 kB / 0.7285 GB, free: 15.11 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.789e-06/0.4964, allocations: 6.406 kB / 0.7285 GB, free: 15.1 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 6.793e-06/0.4964, allocations: 3.844 kB / 0.7285 GB, free: 15.1 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009521/0.4974, allocations: 208.2 kB / 0.7287 GB, free: 15.05 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.845e-05/0.4974, allocations: 10.72 kB / 0.7287 GB, free: 15.05 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.000691/0.4981, allocations: 84.94 kB / 0.7288 GB, free: 15.01 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.973e-05/0.4982, allocations: 28.73 kB / 0.7288 GB, free: 15 MB / 0.4981 GB
Warning: Assuming fixed start value for the following 1 variables:
         plantPVD1.staVmea.local_df_internal:VARIABLE(flow=false fixed = true protected = true )  \"Helping variable/connector\" type: Real
Warning: The initial conditions are over specified. The following 1 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         voltage.y = elmVac.v_0.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 6
 * Number of states: 0 ()
 * Number of discrete variables: 4 ($whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (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
   {(1,8)}
Notification: Performance of prepare postOptimizeDAE: time 0.0003426/0.4985, allocations: 224.1 kB / 0.729 GB, free: 14.79 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001581/0.4987, allocations: 81.23 kB / 0.7291 GB, free: 14.75 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003303/0.502, allocations: 1.249 MB / 0.7303 GB, free: 14.06 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.529e-05/0.502, allocations: 11.91 kB / 0.7303 GB, free: 14.05 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.288e-06/0.502, allocations: 5.188 kB / 0.7303 GB, free: 14.05 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001693/0.5021, allocations: 151.2 kB / 0.7305 GB, free: 13.9 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001253/0.5023, allocations: 20.52 kB / 0.7305 GB, free: 13.88 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007284/0.503, allocations: 107.5 kB / 0.7306 GB, free: 13.84 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001657/0.5032, allocations: 59.38 kB / 0.7306 GB, free: 13.8 MB / 0.4981 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004655/0.5078, allocations: 1.558 MB / 0.7322 GB, free: 13.01 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.004103/0.5119, allocations: 1.89 MB / 0.734 GB, free: 11.94 MB / 0.4981 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001264/0.5132, allocations: 0.5208 MB / 0.7345 GB, free: 11.66 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.0005694/0.5138, allocations: 440.5 kB / 0.7349 GB, free: 11.41 MB / 0.4981 GB
Notification: Performance of Backend: time 3.8e-07/0.5138, allocations: 0 / 0.7349 GB, free: 11.41 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.003407/0.5172, allocations: 1.3 MB / 0.7362 GB, free: 10.7 MB / 0.4981 GB
Notification: Performance of SimCode: time 0.002572/0.5197, allocations: 1.912 MB / 0.7381 GB, free: 9.301 MB / 0.4981 GB
Notification: Performance of Templates: time 0.0285/0.5482, allocations: 17.72 MB / 0.7554 GB, free: 11.05 MB / 0.5137 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.makefile [Timeout 660]
(rm -f OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.pipe ; mkfifo OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.pipe ; head -c 1048576 < OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.pipe >> ../files/OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.sim & ./OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1  -lv=LOG_STATS -s=rungekutta -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_dev_OpenIPSL.Tests.Solar.PowerFactory.PVD1.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.031749852001667]
Failed to read output from testmodel.py, exit status != 0:
0.5570675260387361 0.571128918 0.130773836
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fd6b75d2050>