Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.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.001388/0.001388, allocations: 77.55 kB / 19.76 MB, free: 1.898 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.433/1.433, allocations: 177.2 MB / 200.1 MB, free: 9.496 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.001541/0.001541, allocations: 157.5 kB / 256.6 MB, free: 0.9258 MB / 238.1 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/package.mo): time 0.4363/0.4363, allocations: 80.32 MB / 393.3 MB, free: 8.375 MB / 318.1 MB
"
[Timeout remaining time 179]
Using package OpenIPSL with version 3.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/OpenIPSL 3.1.0/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.Sources.SourcesBehindImpedance.VSource,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource")
translateModel(OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource") [Timeout 660]
"Notification: OpenIPSL requested package Complex of version 4.0.0. Complex 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: OpenIPSL requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002615/0.002615, allocations: 79.94 kB / 0.5558 GB, free: 4.125 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3715/0.3741, allocations: 80.12 MB / 0.6341 GB, free: 5.219 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource): time 0.005773/0.3799, allocations: 5.593 MB / 0.6395 GB, free: 1.43 MB / 494.1 MB
Notification: Performance of NFInst.instExpressions: time 0.003343/0.3832, allocations: 1.788 MB / 0.6413 GB, free: 196 kB / 494.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0004032/0.3836, allocations: 13.94 kB / 0.6413 GB, free: 192 kB / 494.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0005007/0.3841, allocations: 241.7 kB / 0.6415 GB, free: 60 kB / 494.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.001438/0.3856, allocations: 0.6109 MB / 0.6421 GB, free: 15.64 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001772/0.3874, allocations: 0.7152 MB / 0.6428 GB, free: 15.26 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.001815/0.3892, allocations: 1.455 MB / 0.6442 GB, free: 14.54 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0003517/0.3895, allocations: 188.4 kB / 0.6444 GB, free: 14.44 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001126/0.3906, allocations: 0.8746 MB / 0.6453 GB, free: 14.01 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0009338/0.3916, allocations: 0.6881 MB / 0.6459 GB, free: 13.72 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001941/0.3918, allocations: 113 kB / 0.646 GB, free: 13.72 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.0004878/0.3923, allocations: 216.6 kB / 0.6462 GB, free: 13.69 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.0003135/0.3926, allocations: 301.2 kB / 0.6465 GB, free: 13.49 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.0005807/0.3932, allocations: 0.5172 MB / 0.647 GB, free: 13.18 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.001932/0.3951, allocations: 1.553 MB / 0.6486 GB, free: 12.49 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 4.749e-06/0.3951, allocations: 0.7031 kB / 0.6486 GB, free: 12.49 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.453e-06/0.3951, allocations: 1 kB / 0.6486 GB, free: 12.49 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 1.916e-05/0.3951, allocations: 0 / 0.6486 GB, free: 12.49 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 180
 * Number of variables: 180
Notification: Performance of Generate backend data structure: time 0.009592/0.4047, allocations: 2.545 MB / 0.651 GB, free: 11.21 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.808e-05/0.4048, allocations: 8.625 kB / 0.6511 GB, free: 11.21 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004595/0.4093, allocations: 0.6873 MB / 0.6517 GB, free: 10.92 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001541/0.4109, allocations: 0.7305 MB / 0.6524 GB, free: 10.32 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005027/0.4114, allocations: 445.6 kB / 0.6529 GB, free: 9.863 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001692/0.4116, allocations: 73.03 kB / 0.6529 GB, free: 9.84 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002621/0.4142, allocations: 1.163 MB / 0.6541 GB, free: 9.145 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.54e-05/0.4142, allocations: 9.094 kB / 0.6541 GB, free: 9.137 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001148/0.4143, allocations: 26.69 kB / 0.6541 GB, free: 9.117 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 1.606e-05/0.4143, allocations: 17.31 kB / 0.6541 GB, free: 9.117 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00269/0.417, allocations: 1.083 MB / 0.6552 GB, free: 8.59 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.006665/0.4237, allocations: 2.944 MB / 0.6581 GB, free: 6.418 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.008183/0.4319, allocations: 1.958 MB / 0.66 GB, free: 5.535 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.00177/0.4336, allocations: 0.6777 MB / 0.6606 GB, free: 5.195 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001144/0.4338, allocations: 26.47 kB / 0.6606 GB, free: 5.18 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.71e-05/0.4338, allocations: 40.88 kB / 0.6607 GB, free: 5.133 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=90): time 3.507e-06/0.4338, allocations: 0 / 0.6607 GB, free: 5.133 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=90): time 0.03995/0.4738, allocations: 8.413 MB / 0.6689 GB, free: 0.9961 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.966e-05/0.4738, allocations: 90.47 kB / 0.669 GB, free: 0.8984 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001664/0.4755, allocations: 0.8997 MB / 0.6699 GB, free: 392 kB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001576/0.4756, allocations: 41.8 kB / 0.6699 GB, free: 348 kB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004534/0.4761, allocations: 476.5 kB / 0.6704 GB, free: 15.92 MB / 0.5137 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0004533/0.4766, allocations: 335.7 kB / 0.6707 GB, free: 15.62 MB / 0.5137 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004397/0.477, allocations: 156.2 kB / 0.6708 GB, free: 15.53 MB / 0.5137 GB
Notification: Performance of setup shared object (initialization): time 0.0001804/0.4772, allocations: 346.9 kB / 0.6712 GB, free: 15.19 MB / 0.5137 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001826/0.479, allocations: 0.6628 MB / 0.6718 GB, free: 14.87 MB / 0.5137 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001961/0.481, allocations: 0.8107 MB / 0.6726 GB, free: 14.37 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003698/0.4847, allocations: 1.498 MB / 0.6741 GB, free: 13.52 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.566e-06/0.4847, allocations: 1.375 kB / 0.6741 GB, free: 13.52 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=121) (initialization): time 0.01795/0.5026, allocations: 4.479 MB / 0.6784 GB, free: 10.02 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 3.29e-05/0.5027, allocations: 14.8 kB / 0.6785 GB, free: 10.01 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.419e-05/0.5027, allocations: 4.375 kB / 0.6785 GB, free: 10.01 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.009142/0.5118, allocations: 2.29 MB / 0.6807 GB, free: 7.715 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00077/0.5126, allocations: 131.8 kB / 0.6808 GB, free: 7.586 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03375/0.5463, allocations: 10.75 MB / 0.6913 GB, free: 12.72 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001887/0.5482, allocations: 159.3 kB / 0.6915 GB, free: 12.57 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001873/0.5484, allocations: 39.97 kB / 0.6915 GB, free: 12.53 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 17
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (79):
 * Single equations (assignments): 78
 * 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
   {(26,17)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005723/0.549, allocations: 229.7 kB / 0.6917 GB, free: 12.29 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003805/0.5494, allocations: 119.2 kB / 0.6918 GB, free: 12.18 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01779/0.5671, allocations: 4.403 MB / 0.6961 GB, free: 7.754 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.906e-05/0.5672, allocations: 11.98 kB / 0.6962 GB, free: 7.742 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.697e-06/0.5672, allocations: 0 / 0.6962 GB, free: 7.742 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003863/0.5676, allocations: 135 kB / 0.6963 GB, free: 7.609 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002242/0.5678, allocations: 16 kB / 0.6963 GB, free: 7.594 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001296/0.5691, allocations: 107.9 kB / 0.6964 GB, free: 7.488 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00138/0.5705, allocations: 0.5273 MB / 0.6969 GB, free: 6.961 MB / 0.5294 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.007338/0.5778, allocations: 2.223 MB / 0.6991 GB, free: 4.727 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005544/0.5833, allocations: 2.321 MB / 0.7014 GB, free: 2.398 MB / 0.5294 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0016/0.5849, allocations: 0.5778 MB / 0.7019 GB, free: 1.82 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.0009458/0.5859, allocations: 0.6107 MB / 0.7025 GB, free: 1.199 MB / 0.5294 GB
Notification: Performance of Backend: time 5.11e-07/0.5859, allocations: 4 kB / 0.7025 GB, free: 1.195 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.007932/0.5938, allocations: 1.945 MB / 0.7044 GB, free: 15.22 MB / 0.545 GB
Notification: Performance of SimCode: time 0.003527/0.5973, allocations: 2.622 MB / 0.707 GB, free: 12.54 MB / 0.545 GB
Notification: Performance of Templates: time 0.04063/0.638, allocations: 25.95 MB / 0.7323 GB, free: 2.656 MB / 0.5606 GB
"
[Timeout remaining time 659]
make -j1 -f OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.makefile [Timeout 660]
(rm -f OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.pipe ; mkfifo OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.pipe ; head -c 1048576 < OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.pipe >> ../files/OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.sim & ./OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource  -abortSlowSimulation -alarm=480 -lv LOG_STATS > OpenIPSL_OpenIPSL.Tests.Sources.SourcesBehindImpedance.VSource.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 6.260611147154123]
Failed to read output from testmodel.py, exit status != 0:
0.6462098229676485 0.660936965 0.26272217699999995
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7faaad552fe0>