./chc-comp-wrapper.sh ../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-094.smt2 .


--------------------------------------------------------------------------------


This is Ultimate 0.3.0-dev-42d8767-m
[2025-04-28 21:21:11,345 INFO  L188        SettingsManager]: Resetting all preferences to default values...
[2025-04-28 21:21:11,422 INFO  L114        SettingsManager]: Loading settings from /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_5ff69512-fcb0-4269-bb3e-bb714c3ec8c9/chc-comp25-scripts/participants/UltimateUnihorn/./chccomp-Unihorn_Default.epf
[2025-04-28 21:21:11,427 WARN  L101        SettingsManager]: Preference file contains the following unknown settings:
[2025-04-28 21:21:11,427 WARN  L103        SettingsManager]:   * de.uni_freiburg.informatik.ultimate.core.Log level for class
[2025-04-28 21:21:11,462 INFO  L130        SettingsManager]: Preferences different from defaults after loading the file:
[2025-04-28 21:21:11,462 INFO  L151        SettingsManager]: Preferences of UltimateCore differ from their defaults:
[2025-04-28 21:21:11,463 INFO  L153        SettingsManager]:  * Log level for class=de.uni_freiburg.informatik.ultimate.lib.smtlibutils.quantifier.QuantifierPusher=ERROR;
[2025-04-28 21:21:11,464 INFO  L151        SettingsManager]: Preferences of Boogie Procedure Inliner differ from their defaults:
[2025-04-28 21:21:11,464 INFO  L153        SettingsManager]:  * Ignore calls to procedures called more than once=ONLY_FOR_SEQUENTIAL_PROGRAMS
[2025-04-28 21:21:11,464 INFO  L151        SettingsManager]: Preferences of BlockEncodingV2 differ from their defaults:
[2025-04-28 21:21:11,465 INFO  L153        SettingsManager]:  * Create parallel compositions if possible=false
[2025-04-28 21:21:11,465 INFO  L153        SettingsManager]:  * Use SBE=true
[2025-04-28 21:21:11,465 INFO  L151        SettingsManager]: Preferences of ChcToBoogie differ from their defaults:
[2025-04-28 21:21:11,465 INFO  L153        SettingsManager]:  * Use one large procedure with gotos to replace tail recursive calls=false
[2025-04-28 21:21:11,465 INFO  L151        SettingsManager]: Preferences of RCFGBuilder differ from their defaults:
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * Size of a code block=SequenceOfStatements
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * SMT solver=External_DefaultMode
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-04-28 21:21:11,466 INFO  L151        SettingsManager]: Preferences of TraceAbstraction differ from their defaults:
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * Compute Interpolants along a Counterexample=FPandBP
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * Trace refinement strategy=CAMEL
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in
[2025-04-28 21:21:11,466 INFO  L153        SettingsManager]:  * Automaton type used in concurrency analysis=PETRI_NET
[2025-04-28 21:21:11,467 INFO  L153        SettingsManager]:  * SMT solver=External_ModelsAndUnsatCoreMode
[2025-04-28 21:21:11,467 INFO  L151        SettingsManager]: Preferences of SmtParser differ from their defaults:
[2025-04-28 21:21:11,468 INFO  L153        SettingsManager]:  * SmtParser Mode=UltimateTreeAutomizer
[2025-04-28 21:21:11,810 INFO  L75    nceAwareModelManager]: Repository-Root is: /tmp
[2025-04-28 21:21:11,822 INFO  L261   ainManager$Toolchain]: [Toolchain 1]: Applicable parser(s) successfully (re)initialized
[2025-04-28 21:21:11,824 INFO  L217   ainManager$Toolchain]: [Toolchain 1]: Toolchain selected.
[2025-04-28 21:21:11,827 INFO  L270        PluginConnector]: Initializing SmtParser...
[2025-04-28 21:21:11,827 INFO  L274        PluginConnector]: SmtParser initialized
[2025-04-28 21:21:11,829 INFO  L431   ainManager$Toolchain]: [Toolchain 1]: Parsing single file: /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_5ff69512-fcb0-4269-bb3e-bb714c3ec8c9/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-094.smt2
[2025-04-28 21:21:11,830 INFO  L314              SmtParser]: Parsing .smt2 file as a set of Horn Clauses
[2025-04-28 21:21:11,835 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
unknown
[2025-04-28 21:21:12,294 INFO  L339              SmtParser]: Successfully executed SMT file /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_5ff69512-fcb0-4269-bb3e-bb714c3ec8c9/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-094.smt2
[2025-04-28 21:21:12,301 INFO  L299   ainManager$Toolchain]: ####################### [Toolchain 1] #######################
[2025-04-28 21:21:12,304 INFO  L133        ToolchainWalker]: Walking toolchain with 4 elements.
[2025-04-28 21:21:12,305 INFO  L112        PluginConnector]: ------------------------ChcToBoogie----------------------------
[2025-04-28 21:21:12,306 INFO  L270        PluginConnector]: Initializing ChcToBoogie...
[2025-04-28 21:21:12,306 INFO  L274        PluginConnector]: ChcToBoogie initialized
[2025-04-28 21:21:12,311 INFO  L184        PluginConnector]: Executing the observer ChcToBoogieObserver from plugin ChcToBoogie for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,366 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12 Unit
[2025-04-28 21:21:12,366 INFO  L131        PluginConnector]: ------------------------ END ChcToBoogie----------------------------
[2025-04-28 21:21:12,367 INFO  L112        PluginConnector]: ------------------------Boogie Preprocessor----------------------------
[2025-04-28 21:21:12,367 INFO  L270        PluginConnector]: Initializing Boogie Preprocessor...
[2025-04-28 21:21:12,368 INFO  L274        PluginConnector]: Boogie Preprocessor initialized
[2025-04-28 21:21:12,382 INFO  L184        PluginConnector]: Executing the observer EnsureBoogieModelObserver from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,383 INFO  L184        PluginConnector]: Executing the observer TypeChecker from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,422 INFO  L184        PluginConnector]: Executing the observer ConstExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,426 INFO  L184        PluginConnector]: Executing the observer StructExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,440 INFO  L184        PluginConnector]: Executing the observer ReplaceArrayAssignments from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,445 INFO  L184        PluginConnector]: Executing the observer UnstructureCode from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,448 INFO  L184        PluginConnector]: Executing the observer FunctionInliner from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,454 INFO  L184        PluginConnector]: Executing the observer LTLStepAnnotator from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,455 INFO  L184        PluginConnector]: Executing the observer BoogieSymbolTableConstructor from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,487 INFO  L131        PluginConnector]: ------------------------ END Boogie Preprocessor----------------------------
[2025-04-28 21:21:12,489 INFO  L112        PluginConnector]: ------------------------RCFGBuilder----------------------------
[2025-04-28 21:21:12,492 INFO  L270        PluginConnector]: Initializing RCFGBuilder...
[2025-04-28 21:21:12,492 INFO  L274        PluginConnector]: RCFGBuilder initialized
[2025-04-28 21:21:12,497 INFO  L184        PluginConnector]: Executing the observer RCFGBuilderObserver from plugin RCFGBuilder for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (1/1) ...
[2025-04-28 21:21:12,499 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-04-28 21:21:12,521 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:12,534 INFO  L229       MonitoredProcess]: Starting monitored process 1 with /usr/bin/z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000 (exit command is (exit), workingDir is null)
[2025-04-28 21:21:12,537 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000 (1)] Waiting until timeout for monitored process
[2025-04-28 21:21:12,548 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure False given in one single declaration
[2025-04-28 21:21:12,548 INFO  L130     BoogieDeclarations]: Found specification of procedure False
[2025-04-28 21:21:12,548 INFO  L138     BoogieDeclarations]: Found implementation of procedure False
[2025-04-28 21:21:12,548 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure realizable given in one single declaration
[2025-04-28 21:21:12,548 INFO  L130     BoogieDeclarations]: Found specification of procedure realizable
[2025-04-28 21:21:12,548 INFO  L138     BoogieDeclarations]: Found implementation of procedure realizable
[2025-04-28 21:21:12,548 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcStart__syn given in one single declaration
[2025-04-28 21:21:12,548 INFO  L130     BoogieDeclarations]: Found specification of procedure funcStart__syn
[2025-04-28 21:21:12,549 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcStart__syn
[2025-04-28 21:21:12,549 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS__syn given in one single declaration
[2025-04-28 21:21:12,549 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS__syn
[2025-04-28 21:21:12,549 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS__syn
[2025-04-28 21:21:12,549 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcA__syn given in one single declaration
[2025-04-28 21:21:12,549 INFO  L130     BoogieDeclarations]: Found specification of procedure funcA__syn
[2025-04-28 21:21:12,549 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcA__syn
[2025-04-28 21:21:12,549 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcE__syn given in one single declaration
[2025-04-28 21:21:12,549 INFO  L130     BoogieDeclarations]: Found specification of procedure funcE__syn
[2025-04-28 21:21:12,549 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcE__syn
[2025-04-28 21:21:12,549 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcStart__sem given in one single declaration
[2025-04-28 21:21:12,550 INFO  L130     BoogieDeclarations]: Found specification of procedure funcStart__sem
[2025-04-28 21:21:12,550 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcStart__sem
[2025-04-28 21:21:12,550 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcB__sem given in one single declaration
[2025-04-28 21:21:12,550 INFO  L130     BoogieDeclarations]: Found specification of procedure funcB__sem
[2025-04-28 21:21:12,550 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcB__sem
[2025-04-28 21:21:12,550 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcA__sem given in one single declaration
[2025-04-28 21:21:12,550 INFO  L130     BoogieDeclarations]: Found specification of procedure funcA__sem
[2025-04-28 21:21:12,550 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcA__sem
[2025-04-28 21:21:12,550 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS__sem given in one single declaration
[2025-04-28 21:21:12,550 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS__sem
[2025-04-28 21:21:12,551 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS__sem
[2025-04-28 21:21:12,551 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcE__sem given in one single declaration
[2025-04-28 21:21:12,551 INFO  L130     BoogieDeclarations]: Found specification of procedure funcE__sem
[2025-04-28 21:21:12,551 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcE__sem
[2025-04-28 21:21:12,551 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcB__syn given in one single declaration
[2025-04-28 21:21:12,551 INFO  L130     BoogieDeclarations]: Found specification of procedure funcB__syn
[2025-04-28 21:21:12,551 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcB__syn
[2025-04-28 21:21:12,551 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure Ultimate.START given in one single declaration
[2025-04-28 21:21:12,551 INFO  L130     BoogieDeclarations]: Found specification of procedure Ultimate.START
[2025-04-28 21:21:12,551 INFO  L138     BoogieDeclarations]: Found implementation of procedure Ultimate.START
[2025-04-28 21:21:12,699 INFO  L234             CfgBuilder]: Building ICFG
[2025-04-28 21:21:12,701 INFO  L260             CfgBuilder]: Building CFG for each procedure with an implementation
[2025-04-28 21:21:13,164 INFO  L?                        ?]: Removed 72 outVars from TransFormulas that were not future-live.
[2025-04-28 21:21:13,165 INFO  L283             CfgBuilder]: Performing block encoding
[2025-04-28 21:21:13,188 INFO  L304             CfgBuilder]: Using library mode
[2025-04-28 21:21:13,188 INFO  L312             CfgBuilder]: Removed 1 assume(true) statements.
[2025-04-28 21:21:13,189 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 28.04 09:21:13 BoogieIcfgContainer
[2025-04-28 21:21:13,189 INFO  L131        PluginConnector]: ------------------------ END RCFGBuilder----------------------------
[2025-04-28 21:21:13,192 INFO  L112        PluginConnector]: ------------------------TraceAbstraction----------------------------
[2025-04-28 21:21:13,193 INFO  L270        PluginConnector]: Initializing TraceAbstraction...
[2025-04-28 21:21:13,199 INFO  L274        PluginConnector]: TraceAbstraction initialized
[2025-04-28 21:21:13,199 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 28.04 09:21:12" (1/3) ...
[2025-04-28 21:21:13,200 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@32a72863 and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction OTHER 28.04 09:21:13, skipping insertion in model container
[2025-04-28 21:21:13,201 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:12" (2/3) ...
[2025-04-28 21:21:13,201 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@32a72863 and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction AST 28.04 09:21:13, skipping insertion in model container
[2025-04-28 21:21:13,201 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 28.04 09:21:13" (3/3) ...
[2025-04-28 21:21:13,203 INFO  L128   eAbstractionObserver]: Analyzing ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver
[2025-04-28 21:21:13,221 INFO  L217   ceAbstractionStarter]: Automizer settings: Hoare:None NWA Interpolation:FPandBP Determinization: PREDICATE_ABSTRACTION
[2025-04-28 21:21:13,224 INFO  L151   ceAbstractionStarter]: Applying trace abstraction to ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver that has 13 procedures, 74 locations, 150 edges, 13 initial locations, 0 loop locations, and 1 error locations.
[2025-04-28 21:21:13,291 INFO  L339      AbstractCegarLoop]: ======== Iteration 0 == of CEGAR loop == AllErrorsAtOnce ========
[2025-04-28 21:21:13,311 INFO  L340      AbstractCegarLoop]: Settings: SEPARATE_VIOLATION_CHECK=true, mInterprocedural=true, mMaxIterations=1000000, mWatchIteration=1000000, mArtifact=RCFG, mInterpolation=FPandBP, mInterpolantAutomaton=STRAIGHT_LINE, mDumpAutomata=false, mAutomataFormat=ATS_NUMERATE, mDumpPath=., mDeterminiation=PREDICATE_ABSTRACTION, mMinimize=MINIMIZE_SEVPA, mAutomataTypeConcurrency=PETRI_NET, mHoareTripleChecks=INCREMENTAL, mHoareAnnotationPositions=None, mDumpOnlyReuseAutomata=false, mLimitTraceHistogram=0, mErrorLocTimeLimit=0, mLimitPathProgramCount=0, mCollectInterpolantStatistics=true, mHeuristicEmptinessCheck=false, mHeuristicEmptinessCheckAStarHeuristic=ZERO, mHeuristicEmptinessCheckAStarHeuristicRandomSeed=1337, mHeuristicEmptinessCheckSmtFeatureScoringMethod=DAGSIZE, mSMTFeatureExtraction=false, mSMTFeatureExtractionDumpPath=., mOverrideInterpolantAutomaton=false, mMcrInterpolantMethod=WP, mPorIndependenceSettings=[Lde.uni_freiburg.informatik.ultimate.lib.tracecheckerutils.partialorder.independence.IndependenceSettings;@2ec14247, mLbeIndependenceSettings=[IndependenceType=SEMANTIC, AbstractionType=NONE, UseConditional=false, UseSemiCommutativity=true, Solver=Z3, SolverTimeout=1000ms]
[2025-04-28 21:21:13,314 INFO  L341      AbstractCegarLoop]: Starting to check reachability of 1 error locations.
[2025-04-28 21:21:13,318 INFO  L278                IsEmpty]: Start isEmpty. Operand  has 74 states, 25 states have (on average 1.8) internal successors, (45), 37 states have internal predecessors, (45), 35 states have call successors, (35), 12 states have call predecessors, (35), 12 states have return successors, (35), 25 states have call predecessors, (35), 35 states have call successors, (35)
[2025-04-28 21:21:13,337 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 45
[2025-04-28 21:21:13,340 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:13,341 INFO  L218           NwaCegarLoop]: trace histogram [4, 4, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
[2025-04-28 21:21:13,342 INFO  L403      AbstractCegarLoop]: === Iteration 1 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:13,349 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:13,350 INFO  L85        PathProgramCache]: Analyzing trace with hash 1901910758, now seen corresponding path program 1 times
[2025-04-28 21:21:13,358 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:13,359 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [638384464]
[2025-04-28 21:21:13,359 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:13,360 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:13,434 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 44 statements into 1 equivalence classes.
[2025-04-28 21:21:13,565 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 44 of 44 statements.
[2025-04-28 21:21:13,565 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:13,566 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:14,527 INFO  L134       CoverageAnalysis]: Checked inductivity of 18 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 18 trivial. 0 not checked.
[2025-04-28 21:21:14,527 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:14,528 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [638384464]
[2025-04-28 21:21:14,528 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [638384464] provided 1 perfect and 0 imperfect interpolant sequences
[2025-04-28 21:21:14,528 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-04-28 21:21:14,534 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [6] imperfect sequences [] total 6
[2025-04-28 21:21:14,536 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [1829742831]
[2025-04-28 21:21:14,537 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-04-28 21:21:14,545 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 6 states
[2025-04-28 21:21:14,545 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:14,643 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 6 interpolants.
[2025-04-28 21:21:14,644 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=10, Invalid=20, Unknown=0, NotChecked=0, Total=30
[2025-04-28 21:21:14,646 INFO  L87              Difference]: Start difference. First operand  has 74 states, 25 states have (on average 1.8) internal successors, (45), 37 states have internal predecessors, (45), 35 states have call successors, (35), 12 states have call predecessors, (35), 12 states have return successors, (35), 25 states have call predecessors, (35), 35 states have call successors, (35) Second operand  has 6 states, 4 states have (on average 4.0) internal successors, (16), 6 states have internal predecessors, (16), 4 states have call successors, (11), 1 states have call predecessors, (11), 4 states have return successors, (11), 4 states have call predecessors, (11), 4 states have call successors, (11)
[2025-04-28 21:21:14,851 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:14,852 INFO  L93              Difference]: Finished difference Result 77 states and 119 transitions.
[2025-04-28 21:21:14,854 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 6 states. 
[2025-04-28 21:21:14,855 INFO  L79                 Accepts]: Start accepts. Automaton has  has 6 states, 4 states have (on average 4.0) internal successors, (16), 6 states have internal predecessors, (16), 4 states have call successors, (11), 1 states have call predecessors, (11), 4 states have return successors, (11), 4 states have call predecessors, (11), 4 states have call successors, (11) Word has length 44
[2025-04-28 21:21:14,856 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:14,868 INFO  L225             Difference]: With dead ends: 77
[2025-04-28 21:21:14,872 INFO  L226             Difference]: Without dead ends: 74
[2025-04-28 21:21:14,875 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 6 GetRequests, 2 SyntacticMatches, 0 SemanticMatches, 4 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 0 ImplicationChecksByTransitivity, 0.0s TimeCoverageRelationStatistics Valid=10, Invalid=20, Unknown=0, NotChecked=0, Total=30
[2025-04-28 21:21:14,878 INFO  L437           NwaCegarLoop]: 110 mSDtfsCounter, 2 mSDsluCounter, 320 mSDsCounter, 0 mSdLazyCounter, 18 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 5 SdHoareTripleChecker+Valid, 430 SdHoareTripleChecker+Invalid, 18 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 18 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.1s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:14,883 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [5 Valid, 430 Invalid, 18 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 18 Invalid, 0 Unknown, 0 Unchecked, 0.1s Time]
[2025-04-28 21:21:14,904 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 74 states.
[2025-04-28 21:21:14,938 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 74 to 74.
[2025-04-28 21:21:14,942 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 74 states, 25 states have (on average 1.76) internal successors, (44), 36 states have internal predecessors, (44), 35 states have call successors, (35), 12 states have call predecessors, (35), 13 states have return successors, (36), 25 states have call predecessors, (36), 35 states have call successors, (36)
[2025-04-28 21:21:14,951 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 74 states to 74 states and 115 transitions.
[2025-04-28 21:21:14,954 INFO  L79                 Accepts]: Start accepts. Automaton has 74 states and 115 transitions. Word has length 44
[2025-04-28 21:21:14,955 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:14,955 INFO  L485      AbstractCegarLoop]: Abstraction has 74 states and 115 transitions.
[2025-04-28 21:21:14,956 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 6 states, 4 states have (on average 4.0) internal successors, (16), 6 states have internal predecessors, (16), 4 states have call successors, (11), 1 states have call predecessors, (11), 4 states have return successors, (11), 4 states have call predecessors, (11), 4 states have call successors, (11)
[2025-04-28 21:21:14,956 INFO  L278                IsEmpty]: Start isEmpty. Operand 74 states and 115 transitions.
[2025-04-28 21:21:14,960 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 77
[2025-04-28 21:21:14,960 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:14,961 INFO  L218           NwaCegarLoop]: trace histogram [4, 4, 4, 4, 2, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
[2025-04-28 21:21:14,961 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable1
[2025-04-28 21:21:14,961 INFO  L403      AbstractCegarLoop]: === Iteration 2 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:14,962 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:14,962 INFO  L85        PathProgramCache]: Analyzing trace with hash 1582135375, now seen corresponding path program 1 times
[2025-04-28 21:21:14,962 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:14,962 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [146328650]
[2025-04-28 21:21:14,963 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:14,963 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:15,037 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 76 statements into 1 equivalence classes.
[2025-04-28 21:21:15,119 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 76 of 76 statements.
[2025-04-28 21:21:15,122 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:15,122 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:16,227 INFO  L134       CoverageAnalysis]: Checked inductivity of 39 backedges. 0 proven. 2 refuted. 0 times theorem prover too weak. 37 trivial. 0 not checked.
[2025-04-28 21:21:16,227 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:16,227 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [146328650]
[2025-04-28 21:21:16,227 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [146328650] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:16,227 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [313626378]
[2025-04-28 21:21:16,228 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:16,228 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:16,234 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:16,238 INFO  L229       MonitoredProcess]: Starting monitored process 2 with /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (exit command is (exit), workingDir is null)
[2025-04-28 21:21:16,241 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Waiting until timeout for monitored process
[2025-04-28 21:21:16,444 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 76 statements into 1 equivalence classes.
[2025-04-28 21:21:16,638 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 76 of 76 statements.
[2025-04-28 21:21:16,638 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:16,638 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:16,645 INFO  L256         TraceCheckSpWp]: Trace formula consists of 863 conjuncts, 20 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:16,664 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:16,786 INFO  L189   IndexEqualityManager]: detected not equals via solver
[2025-04-28 21:21:16,799 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 2 stores, 0 select indices, 0 select index equivalence classes, 4 disjoint index pairs (out of 0 index pairs), introduced 0 new quantified variables, introduced 0 case distinctions, treesize of input 37 treesize of output 18
[2025-04-28 21:21:17,232 INFO  L134       CoverageAnalysis]: Checked inductivity of 39 backedges. 0 proven. 2 refuted. 0 times theorem prover too weak. 37 trivial. 0 not checked.
[2025-04-28 21:21:17,233 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:21:18,803 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 0 stores, 1 select indices, 1 select index equivalence classes, 0 disjoint index pairs (out of 0 index pairs), introduced 1 new quantified variables, introduced 0 case distinctions, treesize of input 7 treesize of output 5
[2025-04-28 21:21:19,009 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 0 stores, 1 select indices, 1 select index equivalence classes, 0 disjoint index pairs (out of 0 index pairs), introduced 1 new quantified variables, introduced 0 case distinctions, treesize of input 7 treesize of output 5
[2025-04-28 21:21:19,148 INFO  L134       CoverageAnalysis]: Checked inductivity of 39 backedges. 0 proven. 2 refuted. 0 times theorem prover too weak. 37 trivial. 0 not checked.
[2025-04-28 21:21:19,148 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [313626378] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:21:19,148 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:21:19,148 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [10, 10, 10] total 18
[2025-04-28 21:21:19,149 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [945519788]
[2025-04-28 21:21:19,149 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:21:19,150 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 18 states
[2025-04-28 21:21:19,152 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:19,153 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 18 interpolants.
[2025-04-28 21:21:19,154 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=52, Invalid=254, Unknown=0, NotChecked=0, Total=306
[2025-04-28 21:21:19,154 INFO  L87              Difference]: Start difference. First operand 74 states and 115 transitions. Second operand  has 18 states, 7 states have (on average 4.571428571428571) internal successors, (32), 15 states have internal predecessors, (32), 13 states have call successors, (30), 1 states have call predecessors, (30), 7 states have return successors, (30), 14 states have call predecessors, (30), 13 states have call successors, (30)
[2025-04-28 21:21:19,523 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:19,523 INFO  L93              Difference]: Finished difference Result 83 states and 128 transitions.
[2025-04-28 21:21:19,524 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 10 states. 
[2025-04-28 21:21:19,524 INFO  L79                 Accepts]: Start accepts. Automaton has  has 18 states, 7 states have (on average 4.571428571428571) internal successors, (32), 15 states have internal predecessors, (32), 13 states have call successors, (30), 1 states have call predecessors, (30), 7 states have return successors, (30), 14 states have call predecessors, (30), 13 states have call successors, (30) Word has length 76
[2025-04-28 21:21:19,525 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:19,527 INFO  L225             Difference]: With dead ends: 83
[2025-04-28 21:21:19,527 INFO  L226             Difference]: Without dead ends: 81
[2025-04-28 21:21:19,527 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 165 GetRequests, 145 SyntacticMatches, 0 SemanticMatches, 20 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 70 ImplicationChecksByTransitivity, 0.4s TimeCoverageRelationStatistics Valid=81, Invalid=381, Unknown=0, NotChecked=0, Total=462
[2025-04-28 21:21:19,528 INFO  L437           NwaCegarLoop]: 110 mSDtfsCounter, 7 mSDsluCounter, 1246 mSDsCounter, 0 mSdLazyCounter, 142 mSolverCounterSat, 9 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.2s Time, 0 mProtectedPredicate, 0 mProtectedAction, 17 SdHoareTripleChecker+Valid, 1356 SdHoareTripleChecker+Invalid, 151 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 9 IncrementalHoareTripleChecker+Valid, 142 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.2s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:19,528 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [17 Valid, 1356 Invalid, 151 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [9 Valid, 142 Invalid, 0 Unknown, 0 Unchecked, 0.2s Time]
[2025-04-28 21:21:19,529 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 81 states.
[2025-04-28 21:21:19,543 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 81 to 79.
[2025-04-28 21:21:19,544 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 79 states, 27 states have (on average 1.7037037037037037) internal successors, (46), 38 states have internal predecessors, (46), 36 states have call successors, (36), 12 states have call predecessors, (36), 15 states have return successors, (42), 28 states have call predecessors, (42), 36 states have call successors, (42)
[2025-04-28 21:21:19,547 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 79 states to 79 states and 124 transitions.
[2025-04-28 21:21:19,547 INFO  L79                 Accepts]: Start accepts. Automaton has 79 states and 124 transitions. Word has length 76
[2025-04-28 21:21:19,547 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:19,548 INFO  L485      AbstractCegarLoop]: Abstraction has 79 states and 124 transitions.
[2025-04-28 21:21:19,548 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 18 states, 7 states have (on average 4.571428571428571) internal successors, (32), 15 states have internal predecessors, (32), 13 states have call successors, (30), 1 states have call predecessors, (30), 7 states have return successors, (30), 14 states have call predecessors, (30), 13 states have call successors, (30)
[2025-04-28 21:21:19,548 INFO  L278                IsEmpty]: Start isEmpty. Operand 79 states and 124 transitions.
[2025-04-28 21:21:19,552 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 109
[2025-04-28 21:21:19,552 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:19,553 INFO  L218           NwaCegarLoop]: trace histogram [8, 8, 4, 4, 3, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
[2025-04-28 21:21:19,558 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Ended with exit code 0
[2025-04-28 21:21:19,755 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable2,2 /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:19,755 INFO  L403      AbstractCegarLoop]: === Iteration 3 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:19,756 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:19,756 INFO  L85        PathProgramCache]: Analyzing trace with hash 1471775728, now seen corresponding path program 1 times
[2025-04-28 21:21:19,756 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:19,756 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [2098674445]
[2025-04-28 21:21:19,756 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:19,756 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:19,812 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-04-28 21:21:19,896 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-04-28 21:21:19,896 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:19,898 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:21,011 INFO  L134       CoverageAnalysis]: Checked inductivity of 128 backedges. 0 proven. 8 refuted. 0 times theorem prover too weak. 120 trivial. 0 not checked.
[2025-04-28 21:21:21,012 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:21,012 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [2098674445]
[2025-04-28 21:21:21,012 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [2098674445] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:21,013 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [552384713]
[2025-04-28 21:21:21,013 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:21,013 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:21,016 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:21,018 INFO  L229       MonitoredProcess]: Starting monitored process 3 with /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (exit command is (exit), workingDir is null)
[2025-04-28 21:21:21,021 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Waiting until timeout for monitored process
[2025-04-28 21:21:21,249 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-04-28 21:21:21,467 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-04-28 21:21:21,467 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:21,467 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:21,474 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1232 conjuncts, 15 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:21,481 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:21,527 INFO  L189   IndexEqualityManager]: detected not equals via solver
[2025-04-28 21:21:21,539 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 2 stores, 0 select indices, 0 select index equivalence classes, 4 disjoint index pairs (out of 0 index pairs), introduced 0 new quantified variables, introduced 0 case distinctions, treesize of input 37 treesize of output 18
[2025-04-28 21:21:21,686 INFO  L134       CoverageAnalysis]: Checked inductivity of 128 backedges. 0 proven. 10 refuted. 0 times theorem prover too weak. 118 trivial. 0 not checked.
[2025-04-28 21:21:21,688 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:21:23,265 INFO  L134       CoverageAnalysis]: Checked inductivity of 128 backedges. 0 proven. 10 refuted. 0 times theorem prover too weak. 118 trivial. 0 not checked.
[2025-04-28 21:21:23,265 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [552384713] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:21:23,265 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:21:23,265 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [9, 10, 10] total 12
[2025-04-28 21:21:23,266 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [987229456]
[2025-04-28 21:21:23,266 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:21:23,266 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 12 states
[2025-04-28 21:21:23,266 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:23,267 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 12 interpolants.
[2025-04-28 21:21:23,269 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=27, Invalid=105, Unknown=0, NotChecked=0, Total=132
[2025-04-28 21:21:23,270 INFO  L87              Difference]: Start difference. First operand 79 states and 124 transitions. Second operand  has 12 states, 7 states have (on average 4.571428571428571) internal successors, (32), 11 states have internal predecessors, (32), 7 states have call successors, (20), 1 states have call predecessors, (20), 7 states have return successors, (22), 5 states have call predecessors, (22), 7 states have call successors, (22)
[2025-04-28 21:21:23,463 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:23,464 INFO  L93              Difference]: Finished difference Result 91 states and 148 transitions.
[2025-04-28 21:21:23,464 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 9 states. 
[2025-04-28 21:21:23,465 INFO  L79                 Accepts]: Start accepts. Automaton has  has 12 states, 7 states have (on average 4.571428571428571) internal successors, (32), 11 states have internal predecessors, (32), 7 states have call successors, (20), 1 states have call predecessors, (20), 7 states have return successors, (22), 5 states have call predecessors, (22), 7 states have call successors, (22) Word has length 108
[2025-04-28 21:21:23,465 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:23,467 INFO  L225             Difference]: With dead ends: 91
[2025-04-28 21:21:23,467 INFO  L226             Difference]: Without dead ends: 84
[2025-04-28 21:21:23,467 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 226 GetRequests, 213 SyntacticMatches, 2 SemanticMatches, 11 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 17 ImplicationChecksByTransitivity, 0.2s TimeCoverageRelationStatistics Valid=32, Invalid=124, Unknown=0, NotChecked=0, Total=156
[2025-04-28 21:21:23,468 INFO  L437           NwaCegarLoop]: 104 mSDtfsCounter, 5 mSDsluCounter, 590 mSDsCounter, 0 mSdLazyCounter, 92 mSolverCounterSat, 3 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 13 SdHoareTripleChecker+Valid, 694 SdHoareTripleChecker+Invalid, 95 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 3 IncrementalHoareTripleChecker+Valid, 92 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.2s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:23,468 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [13 Valid, 694 Invalid, 95 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [3 Valid, 92 Invalid, 0 Unknown, 0 Unchecked, 0.2s Time]
[2025-04-28 21:21:23,469 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 84 states.
[2025-04-28 21:21:23,481 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 84 to 82.
[2025-04-28 21:21:23,482 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 82 states, 28 states have (on average 1.6785714285714286) internal successors, (47), 40 states have internal predecessors, (47), 37 states have call successors, (37), 12 states have call predecessors, (37), 16 states have return successors, (50), 29 states have call predecessors, (50), 37 states have call successors, (50)
[2025-04-28 21:21:23,483 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 82 states to 82 states and 134 transitions.
[2025-04-28 21:21:23,483 INFO  L79                 Accepts]: Start accepts. Automaton has 82 states and 134 transitions. Word has length 108
[2025-04-28 21:21:23,484 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:23,484 INFO  L485      AbstractCegarLoop]: Abstraction has 82 states and 134 transitions.
[2025-04-28 21:21:23,484 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 12 states, 7 states have (on average 4.571428571428571) internal successors, (32), 11 states have internal predecessors, (32), 7 states have call successors, (20), 1 states have call predecessors, (20), 7 states have return successors, (22), 5 states have call predecessors, (22), 7 states have call successors, (22)
[2025-04-28 21:21:23,484 INFO  L278                IsEmpty]: Start isEmpty. Operand 82 states and 134 transitions.
[2025-04-28 21:21:23,487 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 109
[2025-04-28 21:21:23,488 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:23,488 INFO  L218           NwaCegarLoop]: trace histogram [8, 8, 4, 3, 3, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
[2025-04-28 21:21:23,495 INFO  L540       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Forceful destruction successful, exit code 0
[2025-04-28 21:21:23,691 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable3,3 /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:23,692 INFO  L403      AbstractCegarLoop]: === Iteration 4 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:23,692 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:23,692 INFO  L85        PathProgramCache]: Analyzing trace with hash -1630598178, now seen corresponding path program 1 times
[2025-04-28 21:21:23,692 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:23,693 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [2014343972]
[2025-04-28 21:21:23,693 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:23,693 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:23,730 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-04-28 21:21:23,810 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-04-28 21:21:23,810 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:23,810 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:25,379 INFO  L134       CoverageAnalysis]: Checked inductivity of 128 backedges. 0 proven. 10 refuted. 0 times theorem prover too weak. 118 trivial. 0 not checked.
[2025-04-28 21:21:25,380 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:25,380 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [2014343972]
[2025-04-28 21:21:25,380 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [2014343972] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:25,380 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [1694607823]
[2025-04-28 21:21:25,380 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:25,381 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:25,383 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:25,386 INFO  L229       MonitoredProcess]: Starting monitored process 4 with /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (exit command is (exit), workingDir is null)
[2025-04-28 21:21:25,389 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (4)] Waiting until timeout for monitored process
[2025-04-28 21:21:25,609 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-04-28 21:21:25,818 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-04-28 21:21:25,818 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:25,818 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:25,826 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1232 conjuncts, 37 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:25,832 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:25,871 INFO  L189   IndexEqualityManager]: detected not equals via solver
[2025-04-28 21:21:25,880 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 2 stores, 0 select indices, 0 select index equivalence classes, 4 disjoint index pairs (out of 0 index pairs), introduced 0 new quantified variables, introduced 0 case distinctions, treesize of input 37 treesize of output 26
[2025-04-28 21:21:26,766 INFO  L134       CoverageAnalysis]: Checked inductivity of 128 backedges. 12 proven. 35 refuted. 0 times theorem prover too weak. 81 trivial. 0 not checked.
[2025-04-28 21:21:26,767 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:21:30,026 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 0 stores, 1 select indices, 1 select index equivalence classes, 0 disjoint index pairs (out of 0 index pairs), introduced 1 new quantified variables, introduced 0 case distinctions, treesize of input 7 treesize of output 5
[2025-04-28 21:21:30,365 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 0 stores, 1 select indices, 1 select index equivalence classes, 0 disjoint index pairs (out of 0 index pairs), introduced 1 new quantified variables, introduced 0 case distinctions, treesize of input 7 treesize of output 5
[2025-04-28 21:21:31,164 INFO  L134       CoverageAnalysis]: Checked inductivity of 128 backedges. 12 proven. 35 refuted. 0 times theorem prover too weak. 81 trivial. 0 not checked.
[2025-04-28 21:21:31,164 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [1694607823] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:21:31,164 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:21:31,164 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [13, 20, 20] total 37
[2025-04-28 21:21:31,164 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [795700177]
[2025-04-28 21:21:31,164 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:21:31,165 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 37 states
[2025-04-28 21:21:31,165 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:31,166 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 37 interpolants.
[2025-04-28 21:21:31,167 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=102, Invalid=1230, Unknown=0, NotChecked=0, Total=1332
[2025-04-28 21:21:31,167 INFO  L87              Difference]: Start difference. First operand 82 states and 134 transitions. Second operand  has 37 states, 16 states have (on average 3.4375) internal successors, (55), 28 states have internal predecessors, (55), 26 states have call successors, (44), 1 states have call predecessors, (44), 16 states have return successors, (44), 23 states have call predecessors, (44), 26 states have call successors, (44)
[2025-04-28 21:21:33,988 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:33,988 INFO  L93              Difference]: Finished difference Result 150 states and 274 transitions.
[2025-04-28 21:21:33,989 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 35 states. 
[2025-04-28 21:21:33,989 INFO  L79                 Accepts]: Start accepts. Automaton has  has 37 states, 16 states have (on average 3.4375) internal successors, (55), 28 states have internal predecessors, (55), 26 states have call successors, (44), 1 states have call predecessors, (44), 16 states have return successors, (44), 23 states have call predecessors, (44), 26 states have call successors, (44) Word has length 108
[2025-04-28 21:21:33,990 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:33,993 INFO  L225             Difference]: With dead ends: 150
[2025-04-28 21:21:33,993 INFO  L226             Difference]: Without dead ends: 138
[2025-04-28 21:21:33,994 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 256 GetRequests, 190 SyntacticMatches, 4 SemanticMatches, 62 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 666 ImplicationChecksByTransitivity, 2.3s TimeCoverageRelationStatistics Valid=373, Invalid=3659, Unknown=0, NotChecked=0, Total=4032
[2025-04-28 21:21:33,995 INFO  L437           NwaCegarLoop]: 100 mSDtfsCounter, 32 mSDsluCounter, 2081 mSDsCounter, 0 mSdLazyCounter, 1272 mSolverCounterSat, 82 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 1.3s Time, 0 mProtectedPredicate, 0 mProtectedAction, 44 SdHoareTripleChecker+Valid, 2181 SdHoareTripleChecker+Invalid, 1354 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 82 IncrementalHoareTripleChecker+Valid, 1272 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 1.5s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:33,996 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [44 Valid, 2181 Invalid, 1354 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [82 Valid, 1272 Invalid, 0 Unknown, 0 Unchecked, 1.5s Time]
[2025-04-28 21:21:33,996 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 138 states.
[2025-04-28 21:21:34,060 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 138 to 95.
[2025-04-28 21:21:34,063 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 95 states, 32 states have (on average 1.59375) internal successors, (51), 45 states have internal predecessors, (51), 42 states have call successors, (42), 12 states have call predecessors, (42), 20 states have return successors, (79), 37 states have call predecessors, (79), 42 states have call successors, (79)
[2025-04-28 21:21:34,066 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 95 states to 95 states and 172 transitions.
[2025-04-28 21:21:34,068 INFO  L79                 Accepts]: Start accepts. Automaton has 95 states and 172 transitions. Word has length 108
[2025-04-28 21:21:34,068 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:34,069 INFO  L485      AbstractCegarLoop]: Abstraction has 95 states and 172 transitions.
[2025-04-28 21:21:34,069 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 37 states, 16 states have (on average 3.4375) internal successors, (55), 28 states have internal predecessors, (55), 26 states have call successors, (44), 1 states have call predecessors, (44), 16 states have return successors, (44), 23 states have call predecessors, (44), 26 states have call successors, (44)
[2025-04-28 21:21:34,069 INFO  L278                IsEmpty]: Start isEmpty. Operand 95 states and 172 transitions.
[2025-04-28 21:21:34,074 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 113
[2025-04-28 21:21:34,074 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:34,074 INFO  L218           NwaCegarLoop]: trace histogram [9, 7, 4, 3, 3, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
[2025-04-28 21:21:34,080 INFO  L540       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (4)] Forceful destruction successful, exit code 0
[2025-04-28 21:21:34,275 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable4,4 /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:34,275 INFO  L403      AbstractCegarLoop]: === Iteration 5 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:34,276 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:34,276 INFO  L85        PathProgramCache]: Analyzing trace with hash -1840212087, now seen corresponding path program 1 times
[2025-04-28 21:21:34,276 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:34,276 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [248426551]
[2025-04-28 21:21:34,276 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:34,276 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:34,313 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 112 statements into 1 equivalence classes.
[2025-04-28 21:21:34,363 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 112 of 112 statements.
[2025-04-28 21:21:34,363 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:34,363 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:36,368 INFO  L134       CoverageAnalysis]: Checked inductivity of 151 backedges. 26 proven. 41 refuted. 0 times theorem prover too weak. 84 trivial. 0 not checked.
[2025-04-28 21:21:36,369 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:36,369 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [248426551]
[2025-04-28 21:21:36,369 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [248426551] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:36,369 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [1111552794]
[2025-04-28 21:21:36,369 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:36,369 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:36,372 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:36,375 INFO  L229       MonitoredProcess]: Starting monitored process 5 with /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (exit command is (exit), workingDir is null)
[2025-04-28 21:21:36,378 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (5)] Waiting until timeout for monitored process
[2025-04-28 21:21:36,622 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 112 statements into 1 equivalence classes.
[2025-04-28 21:21:36,810 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 112 of 112 statements.
[2025-04-28 21:21:36,810 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:36,810 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:36,819 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1277 conjuncts, 62 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:36,827 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:36,847 INFO  L189   IndexEqualityManager]: detected not equals via solver
[2025-04-28 21:21:36,853 INFO  L354             Elim1Store]: Elim1 eliminated variable of array dimension 1, 2 stores, 0 select indices, 0 select index equivalence classes, 4 disjoint index pairs (out of 0 index pairs), introduced 0 new quantified variables, introduced 0 case distinctions, treesize of input 37 treesize of output 26
[2025-04-28 21:21:38,965 INFO  L134       CoverageAnalysis]: Checked inductivity of 151 backedges. 32 proven. 45 refuted. 0 times theorem prover too weak. 74 trivial. 0 not checked.
[2025-04-28 21:21:38,966 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
