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


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


This is Ultimate 0.3.0-dev-42d8767-m
[2025-04-28 21:21:50,725 INFO  L188        SettingsManager]: Resetting all preferences to default values...
[2025-04-28 21:21:50,795 INFO  L114        SettingsManager]: Loading settings from /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_a914bd81-439b-4e44-b722-c29b7bff9081/chc-comp25-scripts/participants/UltimateUnihorn/./chccomp-Unihorn_Default.epf
[2025-04-28 21:21:50,800 WARN  L101        SettingsManager]: Preference file contains the following unknown settings:
[2025-04-28 21:21:50,800 WARN  L103        SettingsManager]:   * de.uni_freiburg.informatik.ultimate.core.Log level for class
[2025-04-28 21:21:50,829 INFO  L130        SettingsManager]: Preferences different from defaults after loading the file:
[2025-04-28 21:21:50,829 INFO  L151        SettingsManager]: Preferences of UltimateCore differ from their defaults:
[2025-04-28 21:21:50,830 INFO  L153        SettingsManager]:  * Log level for class=de.uni_freiburg.informatik.ultimate.lib.smtlibutils.quantifier.QuantifierPusher=ERROR;
[2025-04-28 21:21:50,830 INFO  L151        SettingsManager]: Preferences of Boogie Procedure Inliner differ from their defaults:
[2025-04-28 21:21:50,830 INFO  L153        SettingsManager]:  * Ignore calls to procedures called more than once=ONLY_FOR_SEQUENTIAL_PROGRAMS
[2025-04-28 21:21:50,830 INFO  L151        SettingsManager]: Preferences of BlockEncodingV2 differ from their defaults:
[2025-04-28 21:21:50,831 INFO  L153        SettingsManager]:  * Create parallel compositions if possible=false
[2025-04-28 21:21:50,831 INFO  L153        SettingsManager]:  * Use SBE=true
[2025-04-28 21:21:50,831 INFO  L151        SettingsManager]: Preferences of ChcToBoogie differ from their defaults:
[2025-04-28 21:21:50,831 INFO  L153        SettingsManager]:  * Use one large procedure with gotos to replace tail recursive calls=false
[2025-04-28 21:21:50,831 INFO  L151        SettingsManager]: Preferences of RCFGBuilder differ from their defaults:
[2025-04-28 21:21:50,831 INFO  L153        SettingsManager]:  * Size of a code block=SequenceOfStatements
[2025-04-28 21:21:50,831 INFO  L153        SettingsManager]:  * SMT solver=External_DefaultMode
[2025-04-28 21:21:50,832 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-04-28 21:21:50,832 INFO  L151        SettingsManager]: Preferences of TraceAbstraction differ from their defaults:
[2025-04-28 21:21:50,832 INFO  L153        SettingsManager]:  * Compute Interpolants along a Counterexample=FPandBP
[2025-04-28 21:21:50,832 INFO  L153        SettingsManager]:  * Trace refinement strategy=CAMEL
[2025-04-28 21:21:50,832 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in
[2025-04-28 21:21:50,832 INFO  L153        SettingsManager]:  * Automaton type used in concurrency analysis=PETRI_NET
[2025-04-28 21:21:50,832 INFO  L153        SettingsManager]:  * SMT solver=External_ModelsAndUnsatCoreMode
[2025-04-28 21:21:50,833 INFO  L151        SettingsManager]: Preferences of SmtParser differ from their defaults:
[2025-04-28 21:21:50,833 INFO  L153        SettingsManager]:  * SmtParser Mode=UltimateTreeAutomizer
[2025-04-28 21:21:51,186 INFO  L75    nceAwareModelManager]: Repository-Root is: /tmp
[2025-04-28 21:21:51,195 INFO  L261   ainManager$Toolchain]: [Toolchain 1]: Applicable parser(s) successfully (re)initialized
[2025-04-28 21:21:51,198 INFO  L217   ainManager$Toolchain]: [Toolchain 1]: Toolchain selected.
[2025-04-28 21:21:51,199 INFO  L270        PluginConnector]: Initializing SmtParser...
[2025-04-28 21:21:51,199 INFO  L274        PluginConnector]: SmtParser initialized
[2025-04-28 21:21:51,202 INFO  L431   ainManager$Toolchain]: [Toolchain 1]: Parsing single file: /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_a914bd81-439b-4e44-b722-c29b7bff9081/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-036.smt2
[2025-04-28 21:21:51,203 INFO  L314              SmtParser]: Parsing .smt2 file as a set of Horn Clauses
[2025-04-28 21:21:51,210 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
unknown
[2025-04-28 21:21:51,628 INFO  L339              SmtParser]: Successfully executed SMT file /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_a914bd81-439b-4e44-b722-c29b7bff9081/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp24-benchmarks/LIA-Arrays/chc-comp24-LIA-Arrays-036.smt2
[2025-04-28 21:21:51,631 INFO  L299   ainManager$Toolchain]: ####################### [Toolchain 1] #######################
[2025-04-28 21:21:51,634 INFO  L133        ToolchainWalker]: Walking toolchain with 4 elements.
[2025-04-28 21:21:51,635 INFO  L112        PluginConnector]: ------------------------ChcToBoogie----------------------------
[2025-04-28 21:21:51,636 INFO  L270        PluginConnector]: Initializing ChcToBoogie...
[2025-04-28 21:21:51,636 INFO  L274        PluginConnector]: ChcToBoogie initialized
[2025-04-28 21:21:51,639 INFO  L184        PluginConnector]: Executing the observer ChcToBoogieObserver from plugin ChcToBoogie for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,698 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51 Unit
[2025-04-28 21:21:51,699 INFO  L131        PluginConnector]: ------------------------ END ChcToBoogie----------------------------
[2025-04-28 21:21:51,701 INFO  L112        PluginConnector]: ------------------------Boogie Preprocessor----------------------------
[2025-04-28 21:21:51,701 INFO  L270        PluginConnector]: Initializing Boogie Preprocessor...
[2025-04-28 21:21:51,701 INFO  L274        PluginConnector]: Boogie Preprocessor initialized
[2025-04-28 21:21:51,717 INFO  L184        PluginConnector]: Executing the observer EnsureBoogieModelObserver from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,717 INFO  L184        PluginConnector]: Executing the observer TypeChecker from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,748 INFO  L184        PluginConnector]: Executing the observer ConstExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,748 INFO  L184        PluginConnector]: Executing the observer StructExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,754 INFO  L184        PluginConnector]: Executing the observer ReplaceArrayAssignments from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,756 INFO  L184        PluginConnector]: Executing the observer UnstructureCode from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,762 INFO  L184        PluginConnector]: Executing the observer FunctionInliner from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,764 INFO  L184        PluginConnector]: Executing the observer LTLStepAnnotator from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,770 INFO  L184        PluginConnector]: Executing the observer BoogieSymbolTableConstructor from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,801 INFO  L131        PluginConnector]: ------------------------ END Boogie Preprocessor----------------------------
[2025-04-28 21:21:51,802 INFO  L112        PluginConnector]: ------------------------RCFGBuilder----------------------------
[2025-04-28 21:21:51,802 INFO  L270        PluginConnector]: Initializing RCFGBuilder...
[2025-04-28 21:21:51,806 INFO  L274        PluginConnector]: RCFGBuilder initialized
[2025-04-28 21:21:51,807 INFO  L184        PluginConnector]: Executing the observer RCFGBuilderObserver from plugin RCFGBuilder for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (1/1) ...
[2025-04-28 21:21:51,809 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-04-28 21:21:51,824 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:51,837 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:51,845 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:51,850 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure False given in one single declaration
[2025-04-28 21:21:51,851 INFO  L130     BoogieDeclarations]: Found specification of procedure False
[2025-04-28 21:21:51,851 INFO  L138     BoogieDeclarations]: Found implementation of procedure False
[2025-04-28 21:21:51,851 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure realizable given in one single declaration
[2025-04-28 21:21:51,851 INFO  L130     BoogieDeclarations]: Found specification of procedure realizable
[2025-04-28 21:21:51,851 INFO  L138     BoogieDeclarations]: Found implementation of procedure realizable
[2025-04-28 21:21:51,851 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcStart__syn given in one single declaration
[2025-04-28 21:21:51,851 INFO  L130     BoogieDeclarations]: Found specification of procedure funcStart__syn
[2025-04-28 21:21:51,851 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcStart__syn
[2025-04-28 21:21:51,851 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS__syn given in one single declaration
[2025-04-28 21:21:51,852 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS__syn
[2025-04-28 21:21:51,852 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS__syn
[2025-04-28 21:21:51,852 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS2__syn given in one single declaration
[2025-04-28 21:21:51,852 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS2__syn
[2025-04-28 21:21:51,852 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS2__syn
[2025-04-28 21:21:51,852 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcE__syn given in one single declaration
[2025-04-28 21:21:51,852 INFO  L130     BoogieDeclarations]: Found specification of procedure funcE__syn
[2025-04-28 21:21:51,852 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcE__syn
[2025-04-28 21:21:51,852 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcA__syn given in one single declaration
[2025-04-28 21:21:51,852 INFO  L130     BoogieDeclarations]: Found specification of procedure funcA__syn
[2025-04-28 21:21:51,852 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcA__syn
[2025-04-28 21:21:51,853 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS1__syn given in one single declaration
[2025-04-28 21:21:51,853 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS1__syn
[2025-04-28 21:21:51,853 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS1__syn
[2025-04-28 21:21:51,853 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcStart__sem given in one single declaration
[2025-04-28 21:21:51,853 INFO  L130     BoogieDeclarations]: Found specification of procedure funcStart__sem
[2025-04-28 21:21:51,853 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcStart__sem
[2025-04-28 21:21:51,853 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcB__sem given in one single declaration
[2025-04-28 21:21:51,853 INFO  L130     BoogieDeclarations]: Found specification of procedure funcB__sem
[2025-04-28 21:21:51,853 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcB__sem
[2025-04-28 21:21:51,853 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcA__sem given in one single declaration
[2025-04-28 21:21:51,853 INFO  L130     BoogieDeclarations]: Found specification of procedure funcA__sem
[2025-04-28 21:21:51,853 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcA__sem
[2025-04-28 21:21:51,854 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS__sem given in one single declaration
[2025-04-28 21:21:51,854 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS__sem
[2025-04-28 21:21:51,854 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS__sem
[2025-04-28 21:21:51,854 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS1__sem given in one single declaration
[2025-04-28 21:21:51,854 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS1__sem
[2025-04-28 21:21:51,854 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS1__sem
[2025-04-28 21:21:51,854 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcE__sem given in one single declaration
[2025-04-28 21:21:51,854 INFO  L130     BoogieDeclarations]: Found specification of procedure funcE__sem
[2025-04-28 21:21:51,854 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcE__sem
[2025-04-28 21:21:51,854 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcS2__sem given in one single declaration
[2025-04-28 21:21:51,854 INFO  L130     BoogieDeclarations]: Found specification of procedure funcS2__sem
[2025-04-28 21:21:51,855 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcS2__sem
[2025-04-28 21:21:51,855 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure funcB__syn given in one single declaration
[2025-04-28 21:21:51,855 INFO  L130     BoogieDeclarations]: Found specification of procedure funcB__syn
[2025-04-28 21:21:51,855 INFO  L138     BoogieDeclarations]: Found implementation of procedure funcB__syn
[2025-04-28 21:21:51,855 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure Ultimate.START given in one single declaration
[2025-04-28 21:21:51,855 INFO  L130     BoogieDeclarations]: Found specification of procedure Ultimate.START
[2025-04-28 21:21:51,855 INFO  L138     BoogieDeclarations]: Found implementation of procedure Ultimate.START
[2025-04-28 21:21:52,021 INFO  L234             CfgBuilder]: Building ICFG
[2025-04-28 21:21:52,024 INFO  L260             CfgBuilder]: Building CFG for each procedure with an implementation
[2025-04-28 21:21:52,420 INFO  L?                        ?]: Removed 72 outVars from TransFormulas that were not future-live.
[2025-04-28 21:21:52,420 INFO  L283             CfgBuilder]: Performing block encoding
[2025-04-28 21:21:52,433 INFO  L304             CfgBuilder]: Using library mode
[2025-04-28 21:21:52,434 INFO  L312             CfgBuilder]: Removed 1 assume(true) statements.
[2025-04-28 21:21:52,434 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 28.04 09:21:52 BoogieIcfgContainer
[2025-04-28 21:21:52,434 INFO  L131        PluginConnector]: ------------------------ END RCFGBuilder----------------------------
[2025-04-28 21:21:52,436 INFO  L112        PluginConnector]: ------------------------TraceAbstraction----------------------------
[2025-04-28 21:21:52,436 INFO  L270        PluginConnector]: Initializing TraceAbstraction...
[2025-04-28 21:21:52,440 INFO  L274        PluginConnector]: TraceAbstraction initialized
[2025-04-28 21:21:52,441 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 28.04 09:21:51" (1/3) ...
[2025-04-28 21:21:52,441 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@1cbba013 and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction OTHER 28.04 09:21:52, skipping insertion in model container
[2025-04-28 21:21:52,442 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 28.04 09:21:51" (2/3) ...
[2025-04-28 21:21:52,442 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@1cbba013 and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction AST 28.04 09:21:52, skipping insertion in model container
[2025-04-28 21:21:52,442 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 28.04 09:21:52" (3/3) ...
[2025-04-28 21:21:52,443 INFO  L128   eAbstractionObserver]: Analyzing ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver
[2025-04-28 21:21:52,459 INFO  L217   ceAbstractionStarter]: Automizer settings: Hoare:None NWA Interpolation:FPandBP Determinization: PREDICATE_ABSTRACTION
[2025-04-28 21:21:52,462 INFO  L151   ceAbstractionStarter]: Applying trace abstraction to ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver that has 17 procedures, 84 locations, 144 edges, 17 initial locations, 0 loop locations, and 1 error locations.
[2025-04-28 21:21:52,523 INFO  L339      AbstractCegarLoop]: ======== Iteration 0 == of CEGAR loop == AllErrorsAtOnce ========
[2025-04-28 21:21:52,541 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;@63af8d50, mLbeIndependenceSettings=[IndependenceType=SEMANTIC, AbstractionType=NONE, UseConditional=false, UseSemiCommutativity=true, Solver=Z3, SolverTimeout=1000ms]
[2025-04-28 21:21:52,542 INFO  L341      AbstractCegarLoop]: Starting to check reachability of 1 error locations.
[2025-04-28 21:21:52,546 INFO  L278                IsEmpty]: Start isEmpty. Operand  has 84 states, 33 states have (on average 1.3636363636363635) internal successors, (45), 41 states have internal predecessors, (45), 33 states have call successors, (33), 16 states have call predecessors, (33), 16 states have return successors, (33), 27 states have call predecessors, (33), 33 states have call successors, (33)
[2025-04-28 21:21:52,562 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 57
[2025-04-28 21:21:52,563 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:52,564 INFO  L218           NwaCegarLoop]: trace histogram [4, 4, 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]
[2025-04-28 21:21:52,564 INFO  L403      AbstractCegarLoop]: === Iteration 1 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:52,571 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:52,571 INFO  L85        PathProgramCache]: Analyzing trace with hash -1421149891, now seen corresponding path program 1 times
[2025-04-28 21:21:52,580 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:52,580 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [559426711]
[2025-04-28 21:21:52,581 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:52,581 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:52,655 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 56 statements into 1 equivalence classes.
[2025-04-28 21:21:52,783 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 56 of 56 statements.
[2025-04-28 21:21:52,783 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:52,784 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:53,667 INFO  L134       CoverageAnalysis]: Checked inductivity of 22 backedges. 4 proven. 5 refuted. 0 times theorem prover too weak. 13 trivial. 0 not checked.
[2025-04-28 21:21:53,669 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:53,669 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [559426711]
[2025-04-28 21:21:53,670 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [559426711] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:53,670 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [1450998510]
[2025-04-28 21:21:53,670 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:53,670 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:53,673 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:53,676 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:53,679 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Waiting until timeout for monitored process
[2025-04-28 21:21:53,880 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 56 statements into 1 equivalence classes.
[2025-04-28 21:21:54,035 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 56 of 56 statements.
[2025-04-28 21:21:54,035 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:54,036 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:54,040 INFO  L256         TraceCheckSpWp]: Trace formula consists of 647 conjuncts, 4 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:54,050 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:54,186 INFO  L134       CoverageAnalysis]: Checked inductivity of 22 backedges. 15 proven. 0 refuted. 0 times theorem prover too weak. 7 trivial. 0 not checked.
[2025-04-28 21:21:54,187 INFO  L308         TraceCheckSpWp]: Omiting computation of backward sequence because forward sequence was already perfect
[2025-04-28 21:21:54,187 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [1450998510] provided 1 perfect and 0 imperfect interpolant sequences
[2025-04-28 21:21:54,187 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 1 imperfect interpolant sequences.
[2025-04-28 21:21:54,190 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [4] imperfect sequences [9] total 11
[2025-04-28 21:21:54,193 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [2011638616]
[2025-04-28 21:21:54,194 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-04-28 21:21:54,200 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 4 states
[2025-04-28 21:21:54,200 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:54,258 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 4 interpolants.
[2025-04-28 21:21:54,260 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=20, Invalid=90, Unknown=0, NotChecked=0, Total=110
[2025-04-28 21:21:54,268 INFO  L87              Difference]: Start difference. First operand  has 84 states, 33 states have (on average 1.3636363636363635) internal successors, (45), 41 states have internal predecessors, (45), 33 states have call successors, (33), 16 states have call predecessors, (33), 16 states have return successors, (33), 27 states have call predecessors, (33), 33 states have call successors, (33) Second operand  has 4 states, 3 states have (on average 8.333333333333334) internal successors, (25), 4 states have internal predecessors, (25), 3 states have call successors, (14), 2 states have call predecessors, (14), 3 states have return successors, (14), 2 states have call predecessors, (14), 3 states have call successors, (14)
[2025-04-28 21:21:54,459 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:54,459 INFO  L93              Difference]: Finished difference Result 86 states and 120 transitions.
[2025-04-28 21:21:54,460 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 4 states. 
[2025-04-28 21:21:54,462 INFO  L79                 Accepts]: Start accepts. Automaton has  has 4 states, 3 states have (on average 8.333333333333334) internal successors, (25), 4 states have internal predecessors, (25), 3 states have call successors, (14), 2 states have call predecessors, (14), 3 states have return successors, (14), 2 states have call predecessors, (14), 3 states have call successors, (14) Word has length 56
[2025-04-28 21:21:54,462 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:54,470 INFO  L225             Difference]: With dead ends: 86
[2025-04-28 21:21:54,470 INFO  L226             Difference]: Without dead ends: 86
[2025-04-28 21:21:54,472 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 64 GetRequests, 55 SyntacticMatches, 0 SemanticMatches, 9 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 5 ImplicationChecksByTransitivity, 0.1s TimeCoverageRelationStatistics Valid=20, Invalid=90, Unknown=0, NotChecked=0, Total=110
[2025-04-28 21:21:54,476 INFO  L437           NwaCegarLoop]: 100 mSDtfsCounter, 1 mSDsluCounter, 184 mSDsCounter, 0 mSdLazyCounter, 40 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 2 SdHoareTripleChecker+Valid, 284 SdHoareTripleChecker+Invalid, 40 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 40 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.1s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:54,478 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [2 Valid, 284 Invalid, 40 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 40 Invalid, 0 Unknown, 0 Unchecked, 0.1s Time]
[2025-04-28 21:21:54,494 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 86 states.
[2025-04-28 21:21:54,530 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 86 to 84.
[2025-04-28 21:21:54,531 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 84 states, 33 states have (on average 1.3333333333333333) internal successors, (44), 41 states have internal predecessors, (44), 33 states have call successors, (33), 16 states have call predecessors, (33), 17 states have return successors, (39), 27 states have call predecessors, (39), 33 states have call successors, (39)
[2025-04-28 21:21:54,536 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 84 states to 84 states and 116 transitions.
[2025-04-28 21:21:54,538 INFO  L79                 Accepts]: Start accepts. Automaton has 84 states and 116 transitions. Word has length 56
[2025-04-28 21:21:54,538 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:54,538 INFO  L485      AbstractCegarLoop]: Abstraction has 84 states and 116 transitions.
[2025-04-28 21:21:54,538 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 4 states, 3 states have (on average 8.333333333333334) internal successors, (25), 4 states have internal predecessors, (25), 3 states have call successors, (14), 2 states have call predecessors, (14), 3 states have return successors, (14), 2 states have call predecessors, (14), 3 states have call successors, (14)
[2025-04-28 21:21:54,539 INFO  L278                IsEmpty]: Start isEmpty. Operand 84 states and 116 transitions.
[2025-04-28 21:21:54,542 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 57
[2025-04-28 21:21:54,542 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:54,542 INFO  L218           NwaCegarLoop]: trace histogram [4, 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]
[2025-04-28 21:21:54,549 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Ended with exit code 0
[2025-04-28 21:21:54,743 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable1,2 /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:54,743 INFO  L403      AbstractCegarLoop]: === Iteration 2 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:54,744 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:54,744 INFO  L85        PathProgramCache]: Analyzing trace with hash 980492376, now seen corresponding path program 1 times
[2025-04-28 21:21:54,744 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:54,744 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1031225130]
[2025-04-28 21:21:54,744 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:54,744 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:54,792 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 56 statements into 1 equivalence classes.
[2025-04-28 21:21:54,827 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 56 of 56 statements.
[2025-04-28 21:21:54,827 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:54,827 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:55,321 INFO  L134       CoverageAnalysis]: Checked inductivity of 22 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 22 trivial. 0 not checked.
[2025-04-28 21:21:55,321 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:55,322 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1031225130]
[2025-04-28 21:21:55,322 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1031225130] provided 1 perfect and 0 imperfect interpolant sequences
[2025-04-28 21:21:55,323 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-04-28 21:21:55,323 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [6] imperfect sequences [] total 6
[2025-04-28 21:21:55,323 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [1392135852]
[2025-04-28 21:21:55,323 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-04-28 21:21:55,324 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 6 states
[2025-04-28 21:21:55,324 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:55,325 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 6 interpolants.
[2025-04-28 21:21:55,325 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=10, Invalid=20, Unknown=0, NotChecked=0, Total=30
[2025-04-28 21:21:55,325 INFO  L87              Difference]: Start difference. First operand 84 states and 116 transitions. Second operand  has 6 states, 4 states have (on average 5.25) internal successors, (21), 6 states have internal predecessors, (21), 4 states have call successors, (13), 1 states have call predecessors, (13), 4 states have return successors, (13), 4 states have call predecessors, (13), 4 states have call successors, (13)
[2025-04-28 21:21:55,414 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:55,414 INFO  L93              Difference]: Finished difference Result 89 states and 122 transitions.
[2025-04-28 21:21:55,415 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 6 states. 
[2025-04-28 21:21:55,416 INFO  L79                 Accepts]: Start accepts. Automaton has  has 6 states, 4 states have (on average 5.25) internal successors, (21), 6 states have internal predecessors, (21), 4 states have call successors, (13), 1 states have call predecessors, (13), 4 states have return successors, (13), 4 states have call predecessors, (13), 4 states have call successors, (13) Word has length 56
[2025-04-28 21:21:55,416 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:55,417 INFO  L225             Difference]: With dead ends: 89
[2025-04-28 21:21:55,419 INFO  L226             Difference]: Without dead ends: 86
[2025-04-28 21:21:55,420 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:55,420 INFO  L437           NwaCegarLoop]: 106 mSDtfsCounter, 2 mSDsluCounter, 308 mSDsCounter, 0 mSdLazyCounter, 18 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 5 SdHoareTripleChecker+Valid, 414 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:55,420 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [5 Valid, 414 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:55,421 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 86 states.
[2025-04-28 21:21:55,436 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 86 to 86.
[2025-04-28 21:21:55,437 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 86 states, 34 states have (on average 1.3235294117647058) internal successors, (45), 42 states have internal predecessors, (45), 33 states have call successors, (33), 16 states have call predecessors, (33), 18 states have return successors, (40), 27 states have call predecessors, (40), 33 states have call successors, (40)
[2025-04-28 21:21:55,441 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 86 states to 86 states and 118 transitions.
[2025-04-28 21:21:55,441 INFO  L79                 Accepts]: Start accepts. Automaton has 86 states and 118 transitions. Word has length 56
[2025-04-28 21:21:55,441 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:55,442 INFO  L485      AbstractCegarLoop]: Abstraction has 86 states and 118 transitions.
[2025-04-28 21:21:55,442 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 6 states, 4 states have (on average 5.25) internal successors, (21), 6 states have internal predecessors, (21), 4 states have call successors, (13), 1 states have call predecessors, (13), 4 states have return successors, (13), 4 states have call predecessors, (13), 4 states have call successors, (13)
[2025-04-28 21:21:55,443 INFO  L278                IsEmpty]: Start isEmpty. Operand 86 states and 118 transitions.
[2025-04-28 21:21:55,448 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 101
[2025-04-28 21:21:55,448 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:55,448 INFO  L218           NwaCegarLoop]: trace histogram [4, 4, 4, 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, 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:55,448 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable2
[2025-04-28 21:21:55,448 INFO  L403      AbstractCegarLoop]: === Iteration 3 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:55,449 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:55,449 INFO  L85        PathProgramCache]: Analyzing trace with hash 889718757, now seen corresponding path program 1 times
[2025-04-28 21:21:55,449 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:55,449 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1615101531]
[2025-04-28 21:21:55,449 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:55,449 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:55,500 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 100 statements into 1 equivalence classes.
[2025-04-28 21:21:55,539 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 100 of 100 statements.
[2025-04-28 21:21:55,540 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:55,540 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:55,809 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 2 proven. 9 refuted. 0 times theorem prover too weak. 36 trivial. 0 not checked.
[2025-04-28 21:21:55,811 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:55,811 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1615101531]
[2025-04-28 21:21:55,811 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1615101531] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:55,812 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [1318929521]
[2025-04-28 21:21:55,812 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:55,812 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:55,814 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:55,818 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:55,822 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Waiting until timeout for monitored process
[2025-04-28 21:21:56,058 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 100 statements into 1 equivalence classes.
[2025-04-28 21:21:56,280 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 100 of 100 statements.
[2025-04-28 21:21:56,281 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:56,281 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:56,287 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1176 conjuncts, 4 conjuncts are in the unsatisfiable core
[2025-04-28 21:21:56,296 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:21:56,354 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 25 proven. 0 refuted. 0 times theorem prover too weak. 22 trivial. 0 not checked.
[2025-04-28 21:21:56,358 INFO  L308         TraceCheckSpWp]: Omiting computation of backward sequence because forward sequence was already perfect
[2025-04-28 21:21:56,358 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [1318929521] provided 1 perfect and 0 imperfect interpolant sequences
[2025-04-28 21:21:56,358 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 1 imperfect interpolant sequences.
[2025-04-28 21:21:56,358 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [4] imperfect sequences [4] total 6
[2025-04-28 21:21:56,359 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [1813534479]
[2025-04-28 21:21:56,359 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-04-28 21:21:56,359 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 4 states
[2025-04-28 21:21:56,359 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:21:56,360 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 4 interpolants.
[2025-04-28 21:21:56,360 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=9, Invalid=21, Unknown=0, NotChecked=0, Total=30
[2025-04-28 21:21:56,360 INFO  L87              Difference]: Start difference. First operand 86 states and 118 transitions. Second operand  has 4 states, 3 states have (on average 13.0) internal successors, (39), 4 states have internal predecessors, (39), 3 states have call successors, (24), 2 states have call predecessors, (24), 3 states have return successors, (24), 2 states have call predecessors, (24), 3 states have call successors, (24)
[2025-04-28 21:21:56,479 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:21:56,483 INFO  L93              Difference]: Finished difference Result 90 states and 129 transitions.
[2025-04-28 21:21:56,484 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 4 states. 
[2025-04-28 21:21:56,484 INFO  L79                 Accepts]: Start accepts. Automaton has  has 4 states, 3 states have (on average 13.0) internal successors, (39), 4 states have internal predecessors, (39), 3 states have call successors, (24), 2 states have call predecessors, (24), 3 states have return successors, (24), 2 states have call predecessors, (24), 3 states have call successors, (24) Word has length 100
[2025-04-28 21:21:56,484 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:21:56,486 INFO  L225             Difference]: With dead ends: 90
[2025-04-28 21:21:56,486 INFO  L226             Difference]: Without dead ends: 90
[2025-04-28 21:21:56,486 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 103 GetRequests, 99 SyntacticMatches, 0 SemanticMatches, 4 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 0 ImplicationChecksByTransitivity, 0.0s TimeCoverageRelationStatistics Valid=9, Invalid=21, Unknown=0, NotChecked=0, Total=30
[2025-04-28 21:21:56,487 INFO  L437           NwaCegarLoop]: 102 mSDtfsCounter, 1 mSDsluCounter, 187 mSDsCounter, 0 mSdLazyCounter, 41 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 2 SdHoareTripleChecker+Valid, 289 SdHoareTripleChecker+Invalid, 41 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 41 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.1s IncrementalHoareTripleChecker+Time
[2025-04-28 21:21:56,487 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [2 Valid, 289 Invalid, 41 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 41 Invalid, 0 Unknown, 0 Unchecked, 0.1s Time]
[2025-04-28 21:21:56,490 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 90 states.
[2025-04-28 21:21:56,509 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 90 to 88.
[2025-04-28 21:21:56,510 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 88 states, 35 states have (on average 1.3142857142857143) internal successors, (46), 44 states have internal predecessors, (46), 33 states have call successors, (33), 16 states have call predecessors, (33), 19 states have return successors, (46), 27 states have call predecessors, (46), 33 states have call successors, (46)
[2025-04-28 21:21:56,513 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 88 states to 88 states and 125 transitions.
[2025-04-28 21:21:56,514 INFO  L79                 Accepts]: Start accepts. Automaton has 88 states and 125 transitions. Word has length 100
[2025-04-28 21:21:56,514 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:21:56,514 INFO  L485      AbstractCegarLoop]: Abstraction has 88 states and 125 transitions.
[2025-04-28 21:21:56,518 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 4 states, 3 states have (on average 13.0) internal successors, (39), 4 states have internal predecessors, (39), 3 states have call successors, (24), 2 states have call predecessors, (24), 3 states have return successors, (24), 2 states have call predecessors, (24), 3 states have call successors, (24)
[2025-04-28 21:21:56,518 INFO  L278                IsEmpty]: Start isEmpty. Operand 88 states and 125 transitions.
[2025-04-28 21:21:56,521 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 101
[2025-04-28 21:21:56,521 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:21:56,521 INFO  L218           NwaCegarLoop]: trace histogram [4, 4, 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, 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:56,532 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Ended with exit code 0
[2025-04-28 21:21:56,725 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:56,725 INFO  L403      AbstractCegarLoop]: === Iteration 4 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:21:56,726 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:21:56,726 INFO  L85        PathProgramCache]: Analyzing trace with hash 111367354, now seen corresponding path program 1 times
[2025-04-28 21:21:56,726 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:21:56,726 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [456764811]
[2025-04-28 21:21:56,726 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:56,727 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:21:56,764 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 100 statements into 1 equivalence classes.
[2025-04-28 21:21:56,864 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 100 of 100 statements.
[2025-04-28 21:21:56,867 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:21:56,867 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:21:59,928 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 6 proven. 17 refuted. 0 times theorem prover too weak. 24 trivial. 0 not checked.
[2025-04-28 21:21:59,929 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:21:59,929 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [456764811]
[2025-04-28 21:21:59,929 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [456764811] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:21:59,930 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [2110280880]
[2025-04-28 21:21:59,930 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:21:59,930 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:21:59,932 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:21:59,935 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:59,939 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (4)] Waiting until timeout for monitored process
[2025-04-28 21:22:00,165 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 100 statements into 1 equivalence classes.
[2025-04-28 21:22:00,398 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 100 of 100 statements.
[2025-04-28 21:22:00,399 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:22:00,399 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:22:00,406 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1176 conjuncts, 13 conjuncts are in the unsatisfiable core
[2025-04-28 21:22:00,417 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:22:00,887 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 0 proven. 10 refuted. 0 times theorem prover too weak. 37 trivial. 0 not checked.
[2025-04-28 21:22:00,888 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:22:02,558 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 0 proven. 10 refuted. 0 times theorem prover too weak. 37 trivial. 0 not checked.
[2025-04-28 21:22:02,558 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [2110280880] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:22:02,558 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:22:02,559 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [29, 10, 10] total 37
[2025-04-28 21:22:02,559 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [932868520]
[2025-04-28 21:22:02,559 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:22:02,560 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 37 states
[2025-04-28 21:22:02,560 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:22:02,561 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 37 interpolants.
[2025-04-28 21:22:02,561 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=84, Invalid=1248, Unknown=0, NotChecked=0, Total=1332
[2025-04-28 21:22:02,562 INFO  L87              Difference]: Start difference. First operand 88 states and 125 transitions. Second operand  has 37 states, 20 states have (on average 3.2) internal successors, (64), 29 states have internal predecessors, (64), 22 states have call successors, (40), 1 states have call predecessors, (40), 20 states have return successors, (44), 24 states have call predecessors, (44), 22 states have call successors, (44)
[2025-04-28 21:22:04,339 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-04-28 21:22:04,342 INFO  L93              Difference]: Finished difference Result 121 states and 182 transitions.
[2025-04-28 21:22:04,343 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 29 states. 
[2025-04-28 21:22:04,343 INFO  L79                 Accepts]: Start accepts. Automaton has  has 37 states, 20 states have (on average 3.2) internal successors, (64), 29 states have internal predecessors, (64), 22 states have call successors, (40), 1 states have call predecessors, (40), 20 states have return successors, (44), 24 states have call predecessors, (44), 22 states have call successors, (44) Word has length 100
[2025-04-28 21:22:04,344 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-04-28 21:22:04,346 INFO  L225             Difference]: With dead ends: 121
[2025-04-28 21:22:04,350 INFO  L226             Difference]: Without dead ends: 112
[2025-04-28 21:22:04,351 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 244 GetRequests, 194 SyntacticMatches, 3 SemanticMatches, 47 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 242 ImplicationChecksByTransitivity, 1.5s TimeCoverageRelationStatistics Valid=151, Invalid=2201, Unknown=0, NotChecked=0, Total=2352
[2025-04-28 21:22:04,352 INFO  L437           NwaCegarLoop]: 93 mSDtfsCounter, 20 mSDsluCounter, 2570 mSDsCounter, 0 mSdLazyCounter, 1324 mSolverCounterSat, 20 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 1.1s Time, 0 mProtectedPredicate, 0 mProtectedAction, 43 SdHoareTripleChecker+Valid, 2663 SdHoareTripleChecker+Invalid, 1344 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 20 IncrementalHoareTripleChecker+Valid, 1324 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 1.3s IncrementalHoareTripleChecker+Time
[2025-04-28 21:22:04,352 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [43 Valid, 2663 Invalid, 1344 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [20 Valid, 1324 Invalid, 0 Unknown, 0 Unchecked, 1.3s Time]
[2025-04-28 21:22:04,353 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 112 states.
[2025-04-28 21:22:04,376 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 112 to 89.
[2025-04-28 21:22:04,376 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 89 states, 35 states have (on average 1.3142857142857143) internal successors, (46), 44 states have internal predecessors, (46), 34 states have call successors, (34), 16 states have call predecessors, (34), 19 states have return successors, (47), 28 states have call predecessors, (47), 34 states have call successors, (47)
[2025-04-28 21:22:04,378 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 89 states to 89 states and 127 transitions.
[2025-04-28 21:22:04,379 INFO  L79                 Accepts]: Start accepts. Automaton has 89 states and 127 transitions. Word has length 100
[2025-04-28 21:22:04,380 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-04-28 21:22:04,380 INFO  L485      AbstractCegarLoop]: Abstraction has 89 states and 127 transitions.
[2025-04-28 21:22:04,380 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 37 states, 20 states have (on average 3.2) internal successors, (64), 29 states have internal predecessors, (64), 22 states have call successors, (40), 1 states have call predecessors, (40), 20 states have return successors, (44), 24 states have call predecessors, (44), 22 states have call successors, (44)
[2025-04-28 21:22:04,380 INFO  L278                IsEmpty]: Start isEmpty. Operand 89 states and 127 transitions.
[2025-04-28 21:22:04,382 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 101
[2025-04-28 21:22:04,382 INFO  L210           NwaCegarLoop]: Found error trace
[2025-04-28 21:22:04,382 INFO  L218           NwaCegarLoop]: trace histogram [4, 4, 3, 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, 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:22:04,390 INFO  L540       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (4)] Forceful destruction successful, exit code 0
[2025-04-28 21:22:04,587 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:22:04,587 INFO  L403      AbstractCegarLoop]: === Iteration 5 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-04-28 21:22:04,588 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-04-28 21:22:04,588 INFO  L85        PathProgramCache]: Analyzing trace with hash -710262183, now seen corresponding path program 1 times
[2025-04-28 21:22:04,588 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-04-28 21:22:04,588 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1025012897]
[2025-04-28 21:22:04,588 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:22:04,588 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-04-28 21:22:04,626 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 100 statements into 1 equivalence classes.
[2025-04-28 21:22:04,697 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 100 of 100 statements.
[2025-04-28 21:22:04,697 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:22:04,697 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:22:06,428 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 5 proven. 8 refuted. 0 times theorem prover too weak. 34 trivial. 0 not checked.
[2025-04-28 21:22:06,428 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-04-28 21:22:06,428 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1025012897]
[2025-04-28 21:22:06,429 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1025012897] provided 0 perfect and 1 imperfect interpolant sequences
[2025-04-28 21:22:06,429 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [1900689531]
[2025-04-28 21:22:06,429 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-04-28 21:22:06,430 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-04-28 21:22:06,432 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-04-28 21:22:06,434 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:22:06,437 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (5)] Waiting until timeout for monitored process
[2025-04-28 21:22:06,639 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 100 statements into 1 equivalence classes.
[2025-04-28 21:22:06,807 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 100 of 100 statements.
[2025-04-28 21:22:06,807 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-04-28 21:22:06,807 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-04-28 21:22:06,815 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1176 conjuncts, 59 conjuncts are in the unsatisfiable core
[2025-04-28 21:22:06,820 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-04-28 21:22:08,683 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 6 proven. 21 refuted. 0 times theorem prover too weak. 20 trivial. 0 not checked.
[2025-04-28 21:22:08,684 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-04-28 21:22:21,826 INFO  L134       CoverageAnalysis]: Checked inductivity of 47 backedges. 6 proven. 21 refuted. 0 times theorem prover too weak. 20 trivial. 0 not checked.
[2025-04-28 21:22:21,827 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [1900689531] provided 0 perfect and 2 imperfect interpolant sequences
[2025-04-28 21:22:21,827 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-04-28 21:22:21,827 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [24, 31, 31] total 64
[2025-04-28 21:22:21,827 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [456501904]
[2025-04-28 21:22:21,827 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-04-28 21:22:21,828 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 64 states
[2025-04-28 21:22:21,828 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-04-28 21:22:21,829 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 64 interpolants.
[2025-04-28 21:22:21,831 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=159, Invalid=3873, Unknown=0, NotChecked=0, Total=4032
[2025-04-28 21:22:21,832 INFO  L87              Difference]: Start difference. First operand 89 states and 127 transitions. Second operand  has 64 states, 30 states have (on average 2.6) internal successors, (78), 47 states have internal predecessors, (78), 41 states have call successors, (53), 1 states have call predecessors, (53), 30 states have return successors, (59), 43 states have call predecessors, (59), 41 states have call successors, (59)
