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


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


This is Ultimate 0.3.0-dev-42d8767-m
[2025-04-28 21:21:12,389 INFO  L188        SettingsManager]: Resetting all preferences to default values...
[2025-04-28 21:21:12,493 INFO  L114        SettingsManager]: Loading settings from /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_92ab27d9-6410-43f0-89b4-97b026441a61/chc-comp25-scripts/participants/UltimateUnihorn/./chccomp-Unihorn_Default.epf
[2025-04-28 21:21:12,501 WARN  L101        SettingsManager]: Preference file contains the following unknown settings:
[2025-04-28 21:21:12,501 WARN  L103        SettingsManager]:   * de.uni_freiburg.informatik.ultimate.core.Log level for class
[2025-04-28 21:21:12,534 INFO  L130        SettingsManager]: Preferences different from defaults after loading the file:
[2025-04-28 21:21:12,534 INFO  L151        SettingsManager]: Preferences of UltimateCore differ from their defaults:
[2025-04-28 21:21:12,534 INFO  L153        SettingsManager]:  * Log level for class=de.uni_freiburg.informatik.ultimate.lib.smtlibutils.quantifier.QuantifierPusher=ERROR;
[2025-04-28 21:21:12,537 INFO  L151        SettingsManager]: Preferences of Boogie Procedure Inliner differ from their defaults:
[2025-04-28 21:21:12,537 INFO  L153        SettingsManager]:  * Ignore calls to procedures called more than once=ONLY_FOR_SEQUENTIAL_PROGRAMS
[2025-04-28 21:21:12,538 INFO  L151        SettingsManager]: Preferences of BlockEncodingV2 differ from their defaults:
[2025-04-28 21:21:12,538 INFO  L153        SettingsManager]:  * Create parallel compositions if possible=false
[2025-04-28 21:21:12,538 INFO  L153        SettingsManager]:  * Use SBE=true
[2025-04-28 21:21:12,538 INFO  L151        SettingsManager]: Preferences of ChcToBoogie differ from their defaults:
[2025-04-28 21:21:12,538 INFO  L153        SettingsManager]:  * Use one large procedure with gotos to replace tail recursive calls=false
[2025-04-28 21:21:12,539 INFO  L151        SettingsManager]: Preferences of RCFGBuilder differ from their defaults:
[2025-04-28 21:21:12,539 INFO  L153        SettingsManager]:  * Size of a code block=SequenceOfStatements
[2025-04-28 21:21:12,539 INFO  L153        SettingsManager]:  * SMT solver=External_DefaultMode
[2025-04-28 21:21:12,539 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-04-28 21:21:12,539 INFO  L151        SettingsManager]: Preferences of TraceAbstraction differ from their defaults:
[2025-04-28 21:21:12,539 INFO  L153        SettingsManager]:  * Compute Interpolants along a Counterexample=FPandBP
[2025-04-28 21:21:12,539 INFO  L153        SettingsManager]:  * Trace refinement strategy=CAMEL
[2025-04-28 21:21:12,539 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in
[2025-04-28 21:21:12,540 INFO  L153        SettingsManager]:  * Automaton type used in concurrency analysis=PETRI_NET
[2025-04-28 21:21:12,540 INFO  L153        SettingsManager]:  * SMT solver=External_ModelsAndUnsatCoreMode
[2025-04-28 21:21:12,540 INFO  L151        SettingsManager]: Preferences of SmtParser differ from their defaults:
[2025-04-28 21:21:12,541 INFO  L153        SettingsManager]:  * SmtParser Mode=UltimateTreeAutomizer
[2025-04-28 21:21:12,901 INFO  L75    nceAwareModelManager]: Repository-Root is: /tmp
[2025-04-28 21:21:12,913 INFO  L261   ainManager$Toolchain]: [Toolchain 1]: Applicable parser(s) successfully (re)initialized
[2025-04-28 21:21:12,915 INFO  L217   ainManager$Toolchain]: [Toolchain 1]: Toolchain selected.
[2025-04-28 21:21:12,918 INFO  L270        PluginConnector]: Initializing SmtParser...
[2025-04-28 21:21:12,919 INFO  L274        PluginConnector]: SmtParser initialized
[2025-04-28 21:21:12,920 INFO  L431   ainManager$Toolchain]: [Toolchain 1]: Parsing single file: /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_92ab27d9-6410-43f0-89b4-97b026441a61/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-111.smt2
[2025-04-28 21:21:12,923 INFO  L314              SmtParser]: Parsing .smt2 file as a set of Horn Clauses
[2025-04-28 21:21:12,927 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
unknown
[2025-04-28 21:21:13,376 INFO  L339              SmtParser]: Successfully executed SMT file /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_92ab27d9-6410-43f0-89b4-97b026441a61/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-111.smt2
[2025-04-28 21:21:13,380 INFO  L299   ainManager$Toolchain]: ####################### [Toolchain 1] #######################
[2025-04-28 21:21:13,381 INFO  L133        ToolchainWalker]: Walking toolchain with 4 elements.
[2025-04-28 21:21:13,382 INFO  L112        PluginConnector]: ------------------------ChcToBoogie----------------------------
[2025-04-28 21:21:13,382 INFO  L270        PluginConnector]: Initializing ChcToBoogie...
[2025-04-28 21:21:13,383 INFO  L274        PluginConnector]: ChcToBoogie initialized
[2025-04-28 21:21:13,385 INFO  L184        PluginConnector]: Executing the observer ChcToBoogieObserver from plugin ChcToBoogie for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,436 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13 Unit
[2025-04-28 21:21:13,436 INFO  L131        PluginConnector]: ------------------------ END ChcToBoogie----------------------------
[2025-04-28 21:21:13,437 INFO  L112        PluginConnector]: ------------------------Boogie Preprocessor----------------------------
[2025-04-28 21:21:13,437 INFO  L270        PluginConnector]: Initializing Boogie Preprocessor...
[2025-04-28 21:21:13,437 INFO  L274        PluginConnector]: Boogie Preprocessor initialized
[2025-04-28 21:21:13,454 INFO  L184        PluginConnector]: Executing the observer EnsureBoogieModelObserver from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,454 INFO  L184        PluginConnector]: Executing the observer TypeChecker from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,490 INFO  L184        PluginConnector]: Executing the observer ConstExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,491 INFO  L184        PluginConnector]: Executing the observer StructExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,500 INFO  L184        PluginConnector]: Executing the observer ReplaceArrayAssignments from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,501 INFO  L184        PluginConnector]: Executing the observer UnstructureCode from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,504 INFO  L184        PluginConnector]: Executing the observer FunctionInliner from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,506 INFO  L184        PluginConnector]: Executing the observer LTLStepAnnotator from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,507 INFO  L184        PluginConnector]: Executing the observer BoogieSymbolTableConstructor from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,522 INFO  L131        PluginConnector]: ------------------------ END Boogie Preprocessor----------------------------
[2025-04-28 21:21:13,523 INFO  L112        PluginConnector]: ------------------------RCFGBuilder----------------------------
[2025-04-28 21:21:13,523 INFO  L270        PluginConnector]: Initializing RCFGBuilder...
[2025-04-28 21:21:13,523 INFO  L274        PluginConnector]: RCFGBuilder initialized
[2025-04-28 21:21:13,524 INFO  L184        PluginConnector]: Executing the observer RCFGBuilderObserver from plugin RCFGBuilder for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (1/1) ...
[2025-04-28 21:21:13,527 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-04-28 21:21:13,550 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:13,566 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:13,570 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:13,584 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure False given in one single declaration
[2025-04-28 21:21:13,584 INFO  L130     BoogieDeclarations]: Found specification of procedure False
[2025-04-28 21:21:13,585 INFO  L138     BoogieDeclarations]: Found implementation of procedure False
[2025-04-28 21:21:13,585 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure realizable given in one single declaration
[2025-04-28 21:21:13,585 INFO  L130     BoogieDeclarations]: Found specification of procedure realizable
[2025-04-28 21:21:13,585 INFO  L138     BoogieDeclarations]: Found implementation of procedure realizable
[2025-04-28 21:21:13,585 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcStart__syn given in one single declaration
[2025-04-28 21:21:13,585 INFO  L130     BoogieDeclarations]: Found specification of procedure funcStart__syn
[2025-04-28 21:21:13,585 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcStart__syn
[2025-04-28 21:21:13,585 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS__syn given in one single declaration
[2025-04-28 21:21:13,585 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS__syn
[2025-04-28 21:21:13,586 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS__syn
[2025-04-28 21:21:13,586 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS1__syn given in one single declaration
[2025-04-28 21:21:13,586 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS1__syn
[2025-04-28 21:21:13,586 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS1__syn
[2025-04-28 21:21:13,586 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcA__syn given in one single declaration
[2025-04-28 21:21:13,587 INFO  L130     BoogieDeclarations]: Found specification of procedure funcA__syn
[2025-04-28 21:21:13,587 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcA__syn
[2025-04-28 21:21:13,588 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS2__syn given in one single declaration
[2025-04-28 21:21:13,588 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS2__syn
[2025-04-28 21:21:13,588 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS2__syn
[2025-04-28 21:21:13,588 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcP__syn given in one single declaration
[2025-04-28 21:21:13,588 INFO  L130     BoogieDeclarations]: Found specification of procedure funcP__syn
[2025-04-28 21:21:13,588 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcP__syn
[2025-04-28 21:21:13,588 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcB__syn given in one single declaration
[2025-04-28 21:21:13,588 INFO  L130     BoogieDeclarations]: Found specification of procedure funcB__syn
[2025-04-28 21:21:13,589 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcB__syn
[2025-04-28 21:21:13,589 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcStart__sem given in one single declaration
[2025-04-28 21:21:13,589 INFO  L130     BoogieDeclarations]: Found specification of procedure funcStart__sem
[2025-04-28 21:21:13,589 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcStart__sem
[2025-04-28 21:21:13,589 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcB__sem given in one single declaration
[2025-04-28 21:21:13,589 INFO  L130     BoogieDeclarations]: Found specification of procedure funcB__sem
[2025-04-28 21:21:13,589 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcB__sem
[2025-04-28 21:21:13,589 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS__sem given in one single declaration
[2025-04-28 21:21:13,589 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS__sem
[2025-04-28 21:21:13,590 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS__sem
[2025-04-28 21:21:13,590 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS2__sem given in one single declaration
[2025-04-28 21:21:13,590 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS2__sem
[2025-04-28 21:21:13,590 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS2__sem
[2025-04-28 21:21:13,590 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcP__sem given in one single declaration
[2025-04-28 21:21:13,590 INFO  L130     BoogieDeclarations]: Found specification of procedure funcP__sem
[2025-04-28 21:21:13,590 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcP__sem
[2025-04-28 21:21:13,590 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS1__sem given in one single declaration
[2025-04-28 21:21:13,590 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS1__sem
[2025-04-28 21:21:13,590 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS1__sem
[2025-04-28 21:21:13,590 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcA__sem given in one single declaration
[2025-04-28 21:21:13,591 INFO  L130     BoogieDeclarations]: Found specification of procedure funcA__sem
[2025-04-28 21:21:13,591 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcA__sem
[2025-04-28 21:21:13,591 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure Ultimate.START given in one single declaration
[2025-04-28 21:21:13,591 INFO  L130     BoogieDeclarations]: Found specification of procedure Ultimate.START
[2025-04-28 21:21:13,591 INFO  L138     BoogieDeclarations]: Found implementation of procedure Ultimate.START
[2025-04-28 21:21:13,786 INFO  L234             CfgBuilder]: Building ICFG
[2025-04-28 21:21:13,790 INFO  L260             CfgBuilder]: Building CFG for each procedure with an implementation
[2025-04-28 21:21:14,185 INFO  L?                        ?]: Removed 77 outVars from TransFormulas that were not future-live.
[2025-04-28 21:21:14,185 INFO  L283             CfgBuilder]: Performing block encoding
[2025-04-28 21:21:14,200 INFO  L304             CfgBuilder]: Using library mode
[2025-04-28 21:21:14,200 INFO  L312             CfgBuilder]: Removed 1 assume(true) statements.
[2025-04-28 21:21:14,200 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 28.04 09:21:14 BoogieIcfgContainer
[2025-04-28 21:21:14,201 INFO  L131        PluginConnector]: ------------------------ END RCFGBuilder----------------------------
[2025-04-28 21:21:14,206 INFO  L112        PluginConnector]: ------------------------TraceAbstraction----------------------------
[2025-04-28 21:21:14,206 INFO  L270        PluginConnector]: Initializing TraceAbstraction...
[2025-04-28 21:21:14,210 INFO  L274        PluginConnector]: TraceAbstraction initialized
[2025-04-28 21:21:14,213 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 28.04 09:21:13" (1/3) ...
[2025-04-28 21:21:14,215 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@7adfec12 and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction OTHER 28.04 09:21:14, skipping insertion in model container
[2025-04-28 21:21:14,215 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:13" (2/3) ...
[2025-04-28 21:21:14,216 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@7adfec12 and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction AST 28.04 09:21:14, skipping insertion in model container
[2025-04-28 21:21:14,216 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 28.04 09:21:14" (3/3) ...
[2025-04-28 21:21:14,218 INFO  L128   eAbstractionObserver]: Analyzing ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver
[2025-04-28 21:21:14,235 INFO  L217   ceAbstractionStarter]: Automizer settings: Hoare:None NWA Interpolation:FPandBP Determinization: PREDICATE_ABSTRACTION
[2025-04-28 21:21:14,238 INFO  L151   ceAbstractionStarter]: Applying trace abstraction to ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver that has 17 procedures, 74 locations, 112 edges, 17 initial locations, 0 loop locations, and 1 error locations.
[2025-04-28 21:21:14,290 INFO  L339      AbstractCegarLoop]: ======== Iteration 0 == of CEGAR loop == AllErrorsAtOnce ========
[2025-04-28 21:21:14,305 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;@42ac645c, mLbeIndependenceSettings=[IndependenceType=SEMANTIC, AbstractionType=NONE, UseConditional=false, UseSemiCommutativity=true, Solver=Z3, SolverTimeout=1000ms]
[2025-04-28 21:21:14,305 INFO  L341      AbstractCegarLoop]: Starting to check reachability of 1 error locations.
[2025-04-28 21:21:14,309 INFO  L278                IsEmpty]: Start isEmpty. Operand  has 74 states, 33 states have (on average 1.303030303030303) internal successors, (43), 39 states have internal predecessors, (43), 23 states have call successors, (23), 16 states have call predecessors, (23), 16 states have return successors, (23), 19 states have call predecessors, (23), 23 states have call successors, (23)
[2025-04-28 21:21:14,320 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 41
[2025-04-28 21:21:14,320 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:14,321 INFO  L218           NwaCegarLoop]: trace histogram [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,323 INFO  L403      AbstractCegarLoop]: === Iteration 1 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:14,329 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:14,330 INFO  L85        PathProgramCache]: Analyzing trace with hash 540391177, now seen corresponding path program 1 times
[2025-04-28 21:21:14,338 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:14,338 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [278730341]
[2025-04-28 21:21:14,339 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:14,339 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:14,404 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 40 statements into 1 equivalence classes.
[2025-04-28 21:21:14,457 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 40 of 40 statements.
[2025-04-28 21:21:14,457 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:14,457 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:14,926 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-04-28 21:21:14,926 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:14,927 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [278730341]
[2025-04-28 21:21:14,927 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [278730341] provided 1 perfect and 0 imperfect interpolant sequences
[2025-04-28 21:21:14,928 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-04-28 21:21:14,931 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [4] imperfect sequences [] total 4
[2025-04-28 21:21:14,933 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [551196860]
[2025-04-28 21:21:14,935 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-04-28 21:21:14,943 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 4 states
[2025-04-28 21:21:14,945 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:14,979 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 4 interpolants.
[2025-04-28 21:21:14,980 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=5, Invalid=7, Unknown=0, NotChecked=0, Total=12
[2025-04-28 21:21:14,982 INFO  L87              Difference]: Start difference. First operand  has 74 states, 33 states have (on average 1.303030303030303) internal successors, (43), 39 states have internal predecessors, (43), 23 states have call successors, (23), 16 states have call predecessors, (23), 16 states have return successors, (23), 19 states have call predecessors, (23), 23 states have call successors, (23) Second operand  has 4 states, 3 states have (on average 6.666666666666667) internal successors, (20), 4 states have internal predecessors, (20), 3 states have call successors, (10), 1 states have call predecessors, (10), 3 states have return successors, (10), 2 states have call predecessors, (10), 3 states have call successors, (10)
[2025-04-28 21:21:15,097 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:15,099 INFO  L93              Difference]: Finished difference Result 74 states and 89 transitions.
[2025-04-28 21:21:15,100 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 4 states. 
[2025-04-28 21:21:15,102 INFO  L79                 Accepts]: Start accepts. Automaton has  has 4 states, 3 states have (on average 6.666666666666667) internal successors, (20), 4 states have internal predecessors, (20), 3 states have call successors, (10), 1 states have call predecessors, (10), 3 states have return successors, (10), 2 states have call predecessors, (10), 3 states have call successors, (10) Word has length 40
[2025-04-28 21:21:15,102 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:15,110 INFO  L225             Difference]: With dead ends: 74
[2025-04-28 21:21:15,111 INFO  L226             Difference]: Without dead ends: 74
[2025-04-28 21:21:15,161 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 4 GetRequests, 2 SyntacticMatches, 0 SemanticMatches, 2 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 0 ImplicationChecksByTransitivity, 0.0s TimeCoverageRelationStatistics Valid=5, Invalid=7, Unknown=0, NotChecked=0, Total=12
[2025-04-28 21:21:15,164 INFO  L437           NwaCegarLoop]: 86 mSDtfsCounter, 1 mSDsluCounter, 164 mSDsCounter, 0 mSdLazyCounter, 6 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 3 SdHoareTripleChecker+Valid, 250 SdHoareTripleChecker+Invalid, 6 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.1s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 6 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.0s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:15,166 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [3 Valid, 250 Invalid, 6 Unknown, 0 Unchecked, 0.1s Time], IncrementalHoareTripleChecker [0 Valid, 6 Invalid, 0 Unknown, 0 Unchecked, 0.0s Time]
[2025-04-28 21:21:15,186 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 74 states.
[2025-04-28 21:21:15,228 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 74 to 74.
[2025-04-28 21:21:15,229 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 74 states, 33 states have (on average 1.2727272727272727) internal successors, (42), 38 states have internal predecessors, (42), 23 states have call successors, (23), 16 states have call predecessors, (23), 17 states have return successors, (24), 19 states have call predecessors, (24), 23 states have call successors, (24)
[2025-04-28 21:21:15,238 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 74 states to 74 states and 89 transitions.
[2025-04-28 21:21:15,242 INFO  L79                 Accepts]: Start accepts. Automaton has 74 states and 89 transitions. Word has length 40
[2025-04-28 21:21:15,242 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:15,242 INFO  L485      AbstractCegarLoop]: Abstraction has 74 states and 89 transitions.
[2025-04-28 21:21:15,242 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 4 states, 3 states have (on average 6.666666666666667) internal successors, (20), 4 states have internal predecessors, (20), 3 states have call successors, (10), 1 states have call predecessors, (10), 3 states have return successors, (10), 2 states have call predecessors, (10), 3 states have call successors, (10)
[2025-04-28 21:21:15,243 INFO  L278                IsEmpty]: Start isEmpty. Operand 74 states and 89 transitions.
[2025-04-28 21:21:15,245 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 69
[2025-04-28 21:21:15,248 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:15,248 INFO  L218           NwaCegarLoop]: trace histogram [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, 1, 1, 1, 1, 1, 1, 1, 1]
[2025-04-28 21:21:15,249 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable1
[2025-04-28 21:21:15,249 INFO  L403      AbstractCegarLoop]: === Iteration 2 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:15,250 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:15,251 INFO  L85        PathProgramCache]: Analyzing trace with hash 434612317, now seen corresponding path program 1 times
[2025-04-28 21:21:15,251 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:15,252 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1425620178]
[2025-04-28 21:21:15,252 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:15,253 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:15,344 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 68 statements into 1 equivalence classes.
[2025-04-28 21:21:15,418 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 68 of 68 statements.
[2025-04-28 21:21:15,418 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:15,418 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:16,462 INFO  L134       CoverageAnalysis]: Checked inductivity of 3 backedges. 0 proven. 2 refuted. 0 times theorem prover too weak. 1 trivial. 0 not checked.
[2025-04-28 21:21:16,462 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:16,462 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1425620178]
[2025-04-28 21:21:16,462 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1425620178] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:16,463 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [561538565]
[2025-04-28 21:21:16,463 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:16,463 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:16,465 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:16,468 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,473 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,677 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 68 statements into 1 equivalence classes.
[2025-04-28 21:21:16,834 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 68 of 68 statements.
[2025-04-28 21:21:16,834 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:16,834 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:16,840 INFO  L256         TraceCheckSpWp]: Trace formula consists of 789 conjuncts, 13 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:16,848 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:17,313 INFO  L134       CoverageAnalysis]: Checked inductivity of 3 backedges. 0 proven. 2 refuted. 0 times theorem prover too weak. 1 trivial. 0 not checked.
[2025-04-28 21:21:17,313 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:21:18,961 INFO  L134       CoverageAnalysis]: Checked inductivity of 3 backedges. 0 proven. 2 refuted. 0 times theorem prover too weak. 1 trivial. 0 not checked.
[2025-04-28 21:21:18,961 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [561538565] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:21:18,961 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:21:18,961 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [8, 8, 8] total 16
[2025-04-28 21:21:18,962 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [2073758992]
[2025-04-28 21:21:18,963 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:21:18,964 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 16 states
[2025-04-28 21:21:18,965 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:18,967 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 16 interpolants.
[2025-04-28 21:21:18,968 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=52, Invalid=188, Unknown=0, NotChecked=0, Total=240
[2025-04-28 21:21:18,968 INFO  L87              Difference]: Start difference. First operand 74 states and 89 transitions. Second operand  has 16 states, 7 states have (on average 7.857142857142857) internal successors, (55), 13 states have internal predecessors, (55), 11 states have call successors, (32), 2 states have call predecessors, (32), 7 states have return successors, (32), 10 states have call predecessors, (32), 11 states have call successors, (32)
[2025-04-28 21:21:19,190 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:19,191 INFO  L93              Difference]: Finished difference Result 76 states and 91 transitions.
[2025-04-28 21:21:19,193 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 8 states. 
[2025-04-28 21:21:19,193 INFO  L79                 Accepts]: Start accepts. Automaton has  has 16 states, 7 states have (on average 7.857142857142857) internal successors, (55), 13 states have internal predecessors, (55), 11 states have call successors, (32), 2 states have call predecessors, (32), 7 states have return successors, (32), 10 states have call predecessors, (32), 11 states have call successors, (32) Word has length 68
[2025-04-28 21:21:19,193 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:19,194 INFO  L225             Difference]: With dead ends: 76
[2025-04-28 21:21:19,196 INFO  L226             Difference]: Without dead ends: 76
[2025-04-28 21:21:19,196 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 145 GetRequests, 129 SyntacticMatches, 0 SemanticMatches, 16 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 50 ImplicationChecksByTransitivity, 0.4s TimeCoverageRelationStatistics Valid=71, Invalid=235, Unknown=0, NotChecked=0, Total=306
[2025-04-28 21:21:19,198 INFO  L437           NwaCegarLoop]: 85 mSDtfsCounter, 8 mSDsluCounter, 559 mSDsCounter, 0 mSdLazyCounter, 41 mSolverCounterSat, 12 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 11 SdHoareTripleChecker+Valid, 644 SdHoareTripleChecker+Invalid, 53 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 12 IncrementalHoareTripleChecker+Valid, 41 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.1s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:19,199 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [11 Valid, 644 Invalid, 53 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [12 Valid, 41 Invalid, 0 Unknown, 0 Unchecked, 0.1s Time]
[2025-04-28 21:21:19,200 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 76 states.
[2025-04-28 21:21:19,208 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 76 to 76.
[2025-04-28 21:21:19,209 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 76 states, 34 states have (on average 1.2647058823529411) internal successors, (43), 39 states have internal predecessors, (43), 23 states have call successors, (23), 16 states have call predecessors, (23), 18 states have return successors, (25), 20 states have call predecessors, (25), 23 states have call successors, (25)
[2025-04-28 21:21:19,211 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 76 states to 76 states and 91 transitions.
[2025-04-28 21:21:19,211 INFO  L79                 Accepts]: Start accepts. Automaton has 76 states and 91 transitions. Word has length 68
[2025-04-28 21:21:19,212 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:19,212 INFO  L485      AbstractCegarLoop]: Abstraction has 76 states and 91 transitions.
[2025-04-28 21:21:19,212 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 16 states, 7 states have (on average 7.857142857142857) internal successors, (55), 13 states have internal predecessors, (55), 11 states have call successors, (32), 2 states have call predecessors, (32), 7 states have return successors, (32), 10 states have call predecessors, (32), 11 states have call successors, (32)
[2025-04-28 21:21:19,212 INFO  L278                IsEmpty]: Start isEmpty. Operand 76 states and 91 transitions.
[2025-04-28 21:21:19,217 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 97
[2025-04-28 21:21:19,219 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:19,219 INFO  L218           NwaCegarLoop]: trace histogram [3, 2, 2, 2, 2, 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, 1, 1, 1]
[2025-04-28 21:21:19,227 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Ended with exit code 0
[2025-04-28 21:21:19,420 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,420 INFO  L403      AbstractCegarLoop]: === Iteration 3 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:19,421 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:19,421 INFO  L85        PathProgramCache]: Analyzing trace with hash -1984378831, now seen corresponding path program 2 times
[2025-04-28 21:21:19,421 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:19,421 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [972654455]
[2025-04-28 21:21:19,421 INFO  L95    rtionOrderModulation]: Changing assertion order to OUTSIDE_LOOP_FIRST1
[2025-04-28 21:21:19,422 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:19,490 INFO  L108    AnnotateAndAsserter]: Assert order OUTSIDE_LOOP_FIRST1 partitioned 96 statements into 2 equivalence classes.
[2025-04-28 21:21:19,559 INFO  L111    AnnotateAndAsserter]: Assert order OUTSIDE_LOOP_FIRST1 issued 2 check-sat command(s) and asserted 96 of 96 statements.
[2025-04-28 21:21:19,559 INFO  L114    AnnotateAndAsserter]: Assert order OUTSIDE_LOOP_FIRST1 issued 2 check-sat command(s)
[2025-04-28 21:21:19,559 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:21,921 INFO  L134       CoverageAnalysis]: Checked inductivity of 34 backedges. 0 proven. 22 refuted. 0 times theorem prover too weak. 12 trivial. 0 not checked.
[2025-04-28 21:21:21,922 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:21,922 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [972654455]
[2025-04-28 21:21:21,922 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [972654455] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:21,922 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [1660178775]
[2025-04-28 21:21:21,923 INFO  L95    rtionOrderModulation]: Changing assertion order to OUTSIDE_LOOP_FIRST1
[2025-04-28 21:21:21,923 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:21,926 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:21,929 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,934 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Waiting until timeout for monitored process
[2025-04-28 21:21:22,172 INFO  L108    AnnotateAndAsserter]: Assert order OUTSIDE_LOOP_FIRST1 partitioned 96 statements into 2 equivalence classes.
[2025-04-28 21:21:22,437 INFO  L111    AnnotateAndAsserter]: Assert order OUTSIDE_LOOP_FIRST1 issued 2 check-sat command(s) and asserted 96 of 96 statements.
[2025-04-28 21:21:22,437 INFO  L114    AnnotateAndAsserter]: Assert order OUTSIDE_LOOP_FIRST1 issued 2 check-sat command(s)
[2025-04-28 21:21:22,438 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:22,446 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1292 conjuncts, 37 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:22,458 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:23,893 INFO  L134       CoverageAnalysis]: Checked inductivity of 34 backedges. 0 proven. 20 refuted. 0 times theorem prover too weak. 14 trivial. 0 not checked.
[2025-04-28 21:21:23,894 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:21:30,707 INFO  L134       CoverageAnalysis]: Checked inductivity of 34 backedges. 0 proven. 20 refuted. 0 times theorem prover too weak. 14 trivial. 0 not checked.
[2025-04-28 21:21:30,708 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [1660178775] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:21:30,708 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:21:30,708 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [17, 16, 16] total 38
[2025-04-28 21:21:30,708 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [494205695]
[2025-04-28 21:21:30,708 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:21:30,709 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 38 states
[2025-04-28 21:21:30,709 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:30,710 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 38 interpolants.
[2025-04-28 21:21:30,711 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=105, Invalid=1301, Unknown=0, NotChecked=0, Total=1406
[2025-04-28 21:21:30,711 INFO  L87              Difference]: Start difference. First operand 76 states and 91 transitions. Second operand  has 38 states, 17 states have (on average 4.588235294117647) internal successors, (78), 29 states have internal predecessors, (78), 25 states have call successors, (49), 2 states have call predecessors, (49), 17 states have return successors, (52), 23 states have call predecessors, (52), 25 states have call successors, (52)
[2025-04-28 21:21:32,609 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:32,609 INFO  L93              Difference]: Finished difference Result 97 states and 125 transitions.
[2025-04-28 21:21:32,610 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 20 states. 
[2025-04-28 21:21:32,610 INFO  L79                 Accepts]: Start accepts. Automaton has  has 38 states, 17 states have (on average 4.588235294117647) internal successors, (78), 29 states have internal predecessors, (78), 25 states have call successors, (49), 2 states have call predecessors, (49), 17 states have return successors, (52), 23 states have call predecessors, (52), 25 states have call successors, (52) Word has length 96
[2025-04-28 21:21:32,611 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:32,612 INFO  L225             Difference]: With dead ends: 97
[2025-04-28 21:21:32,612 INFO  L226             Difference]: Without dead ends: 97
[2025-04-28 21:21:32,614 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 228 GetRequests, 169 SyntacticMatches, 5 SemanticMatches, 54 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 309 ImplicationChecksByTransitivity, 2.7s TimeCoverageRelationStatistics Valid=290, Invalid=2790, Unknown=0, NotChecked=0, Total=3080
[2025-04-28 21:21:32,614 INFO  L437           NwaCegarLoop]: 80 mSDtfsCounter, 26 mSDsluCounter, 1948 mSDsCounter, 0 mSdLazyCounter, 443 mSolverCounterSat, 61 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.5s Time, 0 mProtectedPredicate, 0 mProtectedAction, 26 SdHoareTripleChecker+Valid, 2028 SdHoareTripleChecker+Invalid, 504 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 61 IncrementalHoareTripleChecker+Valid, 443 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.6s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:32,615 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [26 Valid, 2028 Invalid, 504 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [61 Valid, 443 Invalid, 0 Unknown, 0 Unchecked, 0.6s Time]
[2025-04-28 21:21:32,616 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 97 states.
[2025-04-28 21:21:32,636 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 97 to 85.
[2025-04-28 21:21:32,636 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 85 states, 37 states have (on average 1.2432432432432432) internal successors, (46), 43 states have internal predecessors, (46), 26 states have call successors, (26), 16 states have call predecessors, (26), 21 states have return successors, (36), 25 states have call predecessors, (36), 26 states have call successors, (36)
[2025-04-28 21:21:32,638 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 85 states to 85 states and 108 transitions.
[2025-04-28 21:21:32,638 INFO  L79                 Accepts]: Start accepts. Automaton has 85 states and 108 transitions. Word has length 96
[2025-04-28 21:21:32,639 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:32,639 INFO  L485      AbstractCegarLoop]: Abstraction has 85 states and 108 transitions.
[2025-04-28 21:21:32,639 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 38 states, 17 states have (on average 4.588235294117647) internal successors, (78), 29 states have internal predecessors, (78), 25 states have call successors, (49), 2 states have call predecessors, (49), 17 states have return successors, (52), 23 states have call predecessors, (52), 25 states have call successors, (52)
[2025-04-28 21:21:32,639 INFO  L278                IsEmpty]: Start isEmpty. Operand 85 states and 108 transitions.
[2025-04-28 21:21:32,642 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 97
[2025-04-28 21:21:32,642 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:32,642 INFO  L218           NwaCegarLoop]: trace histogram [3, 2, 2, 2, 2, 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, 1, 1, 1]
[2025-04-28 21:21:32,648 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Ended with exit code 0
[2025-04-28 21:21:32,846 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:32,847 INFO  L403      AbstractCegarLoop]: === Iteration 4 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:32,847 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:32,847 INFO  L85        PathProgramCache]: Analyzing trace with hash -1202913239, now seen corresponding path program 1 times
[2025-04-28 21:21:32,847 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:32,848 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [691633382]
[2025-04-28 21:21:32,848 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:32,848 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:32,885 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 96 statements into 1 equivalence classes.
[2025-04-28 21:21:32,921 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 96 of 96 statements.
[2025-04-28 21:21:32,924 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:32,924 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:34,939 INFO  L134       CoverageAnalysis]: Checked inductivity of 34 backedges. 2 proven. 20 refuted. 0 times theorem prover too weak. 12 trivial. 0 not checked.
[2025-04-28 21:21:34,939 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:34,939 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [691633382]
[2025-04-28 21:21:34,939 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [691633382] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:34,939 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [148845811]
[2025-04-28 21:21:34,940 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:34,940 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:34,942 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:34,944 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:34,947 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (4)] Waiting until timeout for monitored process
[2025-04-28 21:21:35,165 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 96 statements into 1 equivalence classes.
[2025-04-28 21:21:35,347 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 96 of 96 statements.
[2025-04-28 21:21:35,347 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:35,347 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:35,357 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1292 conjuncts, 35 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:35,362 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:36,484 INFO  L134       CoverageAnalysis]: Checked inductivity of 34 backedges. 0 proven. 22 refuted. 0 times theorem prover too weak. 12 trivial. 0 not checked.
[2025-04-28 21:21:36,485 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:21:42,438 INFO  L134       CoverageAnalysis]: Checked inductivity of 34 backedges. 0 proven. 22 refuted. 0 times theorem prover too weak. 12 trivial. 0 not checked.
[2025-04-28 21:21:42,438 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [148845811] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:21:42,438 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:21:42,438 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [19, 17, 17] total 41
[2025-04-28 21:21:42,438 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [2114649559]
[2025-04-28 21:21:42,438 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:21:42,439 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 41 states
[2025-04-28 21:21:42,439 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:42,441 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 41 interpolants.
[2025-04-28 21:21:42,441 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=111, Invalid=1529, Unknown=0, NotChecked=0, Total=1640
[2025-04-28 21:21:42,442 INFO  L87              Difference]: Start difference. First operand 85 states and 108 transitions. Second operand  has 41 states, 19 states have (on average 4.2631578947368425) internal successors, (81), 31 states have internal predecessors, (81), 26 states have call successors, (49), 2 states have call predecessors, (49), 19 states have return successors, (52), 25 states have call predecessors, (52), 26 states have call successors, (52)
