Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo", uses=false)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo)
Using package Complex with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo)
Running command: "" <> buildModelFMU(Modelica.Electrical.Spice3.Examples.Nand,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Electrical_Spice3_Examples_Nand",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Modelica.Electrical.Spice3.Examples.Nand,fileNamePrefix="Modelica_4_1_0-beta_om_Modelica_Electrical_Spice3_Examples_Nand",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001341/0.001341, allocations: 121.9 kB / 17.84 MB, free: 5.086 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001878/0.001878, allocations: 206.1 kB / 18.79 MB, free: 4.141 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo): time 1.534/1.534, allocations: 230.4 MB / 250 MB, free: 9.008 MB / 206.1 MB
Warning: Requested package Modelica of version trunk, but this package was already loaded with version 4.1.0. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package Complex of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package ModelicaServices of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.001999/0.001999, allocations: 67.83 kB / 366.8 MB, free: 7.551 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2725/0.2745, allocations: 53.3 MB / 420.1 MB, free: 8.285 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Electrical.Spice3.Examples.Nand): time 0.01503/0.2895, allocations: 14.07 MB / 434.2 MB, free: 10.16 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.02768/0.3172, allocations: 20.36 MB / 454.6 MB, free: 5.723 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004211/0.3214, allocations: 122.9 kB / 454.7 MB, free: 5.602 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004788/0.3262, allocations: 1.131 MB / 455.8 MB, free: 4.465 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.0101/0.3363, allocations: 2.303 MB / 458.1 MB, free: 2.145 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005881/0.3421, allocations: 1.452 MB / 459.6 MB, free: 0.6875 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01179/0.3539, allocations: 6.449 MB / 466 MB, free: 10.22 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007002/0.3546, allocations: 252.5 kB / 466.3 MB, free: 9.938 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001947/0.3566, allocations: 1.062 MB / 467.3 MB, free: 8.871 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.001484/0.3581, allocations: 1.056 MB / 468.4 MB, free: 7.809 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001917/0.3583, allocations: 68 kB / 468.5 MB, free: 7.742 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004632/0.3629, allocations: 1.479 MB / 469.9 MB, free: 6.262 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0003753/0.3633, allocations: 317.8 kB / 470.3 MB, free: 5.949 MB / 382.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001531/0.3648, allocations: 1.292 MB / 471.5 MB, free: 4.648 MB / 382.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006698/0.3715, allocations: 3.648 MB / 475.2 MB, free: 0.9844 MB / 382.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.754e-06/0.3715, allocations: 3.984 kB / 475.2 MB, free: 0.9805 MB / 382.1 MB
Notification: Performance of FrontEnd: time 2.294e-06/0.3715, allocations: 0 / 475.2 MB, free: 0.9805 MB / 382.1 MB
Notification: Performance of Transformations before backend: time 6.238e-05/0.3716, allocations: 0 / 475.2 MB, free: 0.9805 MB / 382.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 146
 * Number of variables: 146
Notification: Performance of Generate backend data structure: time 0.005833/0.3774, allocations: 3.633 MB / 478.8 MB, free: 13.27 MB / 398.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.51e-05/0.3774, allocations: 8.031 kB / 478.8 MB, free: 13.26 MB / 398.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0001341/0.3776, allocations: 94.52 kB / 478.9 MB, free: 13.16 MB / 398.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0004701/0.378, allocations: 393.6 kB / 479.3 MB, free: 12.77 MB / 398.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003668/0.3817, allocations: 2.539 MB / 481.9 MB, free: 10.16 MB / 398.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.558e-05/0.3818, allocations: 33 kB / 481.9 MB, free: 10.13 MB / 398.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001226/0.3819, allocations: 44 kB / 481.9 MB, free: 10.09 MB / 398.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001419/0.3833, allocations: 0.6992 MB / 482.6 MB, free: 9.379 MB / 398.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 1.538e-05/0.3833, allocations: 0 / 482.6 MB, free: 9.379 MB / 398.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 6.574e-05/0.3834, allocations: 20 kB / 482.6 MB, free: 9.359 MB / 398.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.563e-05/0.3834, allocations: 23.98 kB / 482.7 MB, free: 9.336 MB / 398.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001722/0.3851, allocations: 0.785 MB / 483.5 MB, free: 8.551 MB / 398.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.005661/0.3908, allocations: 2.891 MB / 486.3 MB, free: 5.594 MB / 398.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.00139/0.3922, allocations: 0.6351 MB / 487 MB, free: 4.93 MB / 398.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001083/0.3933, allocations: 0.5804 MB / 487.6 MB, free: 4.352 MB / 398.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0356/0.4289, allocations: 30.72 MB / 0.5061 GB, free: 1.375 MB / 430.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0007923/0.4297, allocations: 365.6 kB / 0.5065 GB, free: 1.008 MB / 430.1 MB
Notification: Performance of pre-optimization done (n=49): time 2.324e-06/0.4297, allocations: 0 / 0.5065 GB, free: 1.008 MB / 430.1 MB
Notification: Performance of matching and sorting (n=49): time 0.003422/0.4331, allocations: 1.401 MB / 0.5079 GB, free: 15.58 MB / 446.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.54e-05/0.4331, allocations: 63.34 kB / 0.5079 GB, free: 15.5 MB / 446.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003843/0.437, allocations: 2.803 MB / 0.5107 GB, free: 12.68 MB / 446.1 MB
Notification: Performance of collectPreVariables (initialization): time 8.43e-05/0.4371, allocations: 33.7 kB / 0.5107 GB, free: 12.64 MB / 446.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001392/0.4385, allocations: 1.4 MB / 0.5121 GB, free: 11.23 MB / 446.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0001795/0.4386, allocations: 170.6 kB / 0.5122 GB, free: 11.07 MB / 446.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003122/0.4389, allocations: 226.4 kB / 0.5124 GB, free: 10.84 MB / 446.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001679/0.4391, allocations: 333.9 kB / 0.5128 GB, free: 10.51 MB / 446.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005848/0.4397, allocations: 241.9 kB / 0.513 GB, free: 10.27 MB / 446.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009054/0.4406, allocations: 0.5891 MB / 0.5136 GB, free: 9.512 MB / 446.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001526/0.4421, allocations: 0.8802 MB / 0.5144 GB, free: 8.457 MB / 446.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.972e-05/0.4421, allocations: 4 kB / 0.5144 GB, free: 8.453 MB / 446.1 MB
Notification: Performance of matching and sorting (n=107) (initialization): time 0.002557/0.4447, allocations: 1.286 MB / 0.5157 GB, free: 7.16 MB / 446.1 MB
Notification: Performance of prepare postOptimizeDAE: time 2.636e-05/0.4447, allocations: 8 kB / 0.5157 GB, free: 7.152 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001485/0.4449, allocations: 83.94 kB / 0.5158 GB, free: 7.07 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006472/0.4455, allocations: 279.6 kB / 0.516 GB, free: 6.797 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005629/0.4461, allocations: 171.7 kB / 0.5162 GB, free: 6.629 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001122/0.4472, allocations: 0.8812 MB / 0.5171 GB, free: 5.723 MB / 446.1 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009804/0.4482, allocations: 115.2 kB / 0.5172 GB, free: 5.609 MB / 446.1 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001129/0.4483, allocations: 64 kB / 0.5172 GB, free: 5.547 MB / 446.1 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 55
 * Number of states: 0 ()
 * Number of discrete variables: 14 (vin1.counter,$PRE.vin1.counter,vin2.counter,$PRE.vin2.counter,vin2.counter2,$PRE.vin2.counter2,vin2.T0,$PRE.vin2.T0,vin1.counter2,$PRE.vin1.counter2,vin1.T0,$PRE.vin1.T0,$whenCondition1,$whenCondition2)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (86):
 * Single equations (assignments): 85
 * 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
   {(2,12)}
Notification: Performance of prepare postOptimizeDAE: time 0.0007381/0.449, allocations: 421.5 kB / 0.5176 GB, free: 5.125 MB / 446.1 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005261/0.4496, allocations: 385.6 kB / 0.518 GB, free: 4.746 MB / 446.1 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002867/0.4524, allocations: 1.251 MB / 0.5192 GB, free: 3.473 MB / 446.1 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.346e-06/0.4524, allocations: 7.938 kB / 0.5192 GB, free: 3.465 MB / 446.1 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.069e-05/0.4525, allocations: 11.92 kB / 0.5192 GB, free: 3.453 MB / 446.1 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.855e-06/0.4525, allocations: 3.984 kB / 0.5192 GB, free: 3.449 MB / 446.1 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003901/0.4564, allocations: 2.185 MB / 0.5214 GB, free: 1.184 MB / 446.1 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.997e-05/0.4565, allocations: 68 kB / 0.5214 GB, free: 1.117 MB / 446.1 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0003057/0.4568, allocations: 63.88 kB / 0.5215 GB, free: 1.055 MB / 446.1 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0004904/0.4573, allocations: 260.9 kB / 0.5217 GB, free: 0.7969 MB / 446.1 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.862e-05/0.4573, allocations: 35.98 kB / 0.5218 GB, free: 0.7617 MB / 446.1 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001017/0.4583, allocations: 0.858 MB / 0.5226 GB, free: 15.88 MB / 462.1 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.871e-06/0.4583, allocations: 7.969 kB / 0.5226 GB, free: 15.87 MB / 462.1 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002026/0.4604, allocations: 1.142 MB / 0.5237 GB, free: 14.69 MB / 462.1 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008638/0.4612, allocations: 486.5 kB / 0.5242 GB, free: 14.19 MB / 462.1 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002661/0.4615, allocations: 8 kB / 0.5242 GB, free: 14.18 MB / 462.1 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006322/0.4621, allocations: 31.97 kB / 0.5242 GB, free: 14.15 MB / 462.1 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001625/0.4623, allocations: 59.94 kB / 0.5243 GB, free: 14.09 MB / 462.1 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 7.825e-05/0.4624, allocations: 44 kB / 0.5243 GB, free: 14.05 MB / 462.1 MB
Notification: Performance of sorting global known variables: time 0.002654/0.465, allocations: 1.82 MB / 0.5261 GB, free: 12.23 MB / 462.1 MB
Notification: Performance of sort global known variables: time 6e-08/0.465, allocations: 0 / 0.5261 GB, free: 12.23 MB / 462.1 MB
Notification: Performance of remove unused functions: time 0.001413/0.4664, allocations: 0.5735 MB / 0.5267 GB, free: 11.65 MB / 462.1 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 0 ()
 * Number of discrete variables: 8 ($whenCondition2,$whenCondition1,vin1.T0,vin1.counter,vin1.counter2,vin2.T0,vin2.counter,vin2.counter2)
 * Number of discrete states: 4 (vin1.counter2,vin2.counter2,vin1.counter,vin2.counter)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (30):
 * Single equations (assignments): 23
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 6
 * 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
   {(2,12)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.003515/0.47, allocations: 2.362 MB / 0.529 GB, free: 9.246 MB / 462.1 MB
Notification: Performance of simCode: created initialization part: time 0.001944/0.4719, allocations: 0.819 MB / 0.5298 GB, free: 8.406 MB / 462.1 MB
Notification: Performance of simCode: created event and clocks part: time 3.346e-06/0.4719, allocations: 128 / 0.5298 GB, free: 8.406 MB / 462.1 MB
Notification: Performance of simCode: created simulation system equations: time 0.0009255/0.4728, allocations: 472.1 kB / 0.5302 GB, free: 7.922 MB / 462.1 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002713/0.4755, allocations: 0.6029 MB / 0.5308 GB, free: 7.312 MB / 462.1 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2674/0.743, allocations: 16.03 MB / 0.5465 GB, free: 119 MB / 462.1 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007731/0.7437, allocations: 1.602 MB / 0.548 GB, free: 118.3 MB / 462.1 MB
Notification: Performance of simCode: alias equations: time 0.001039/0.7448, allocations: 390.8 kB / 0.5484 GB, free: 118.2 MB / 462.1 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0009005/0.7457, allocations: 1.125 MB / 0.5495 GB, free: 117.3 MB / 462.1 MB
Notification: Performance of SimCode: time 1.572e-06/0.7457, allocations: 0 / 0.5495 GB, free: 117.3 MB / 462.1 MB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.09906/0.8447, allocations: 62.78 MB / 0.6108 GB, free: 56.59 MB / 462.1 MB
Notification: Performance of buildModelFMU: Generate platform static: time 19.12/19.96, allocations: 4.047 kB / 0.6108 GB, free: 56.59 MB / 462.1 MB
(rm -f Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand.sim & python3 -m fmpy simulate  --output-file Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand_res.csv --start-time 0 --stop-time 2e-07 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 8e-11 Modelica_4_1_0-beta_om_Modelica_Electrical_Spice3_Examples_Nand.fmu > Modelica_4.1.0-beta.om_Modelica.Electrical.Spice3.Examples.Nand.pipe 2>&1)