./chc-comp-wrapper.sh ../../../chc-comp25-benchmarks/solidity/no_adts/unit_tests/functions/virtual_function_called_by_constructor.sol_5_no_adts_000.smt2 .


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


This is Ultimate 0.3.0-dev-42d8767-m
[2025-05-07 17:29:22,780 INFO  L188        SettingsManager]: Resetting all preferences to default values...
[2025-05-07 17:29:22,817 INFO  L114        SettingsManager]: Loading settings from /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_bca83790-87e9-4477-9c67-d7afe36cffb5/chc-comp25-scripts/participants/UltimateUnihorn/./chccomp-Unihorn_Default.epf
[2025-05-07 17:29:22,820 WARN  L101        SettingsManager]: Preference file contains the following unknown settings:
[2025-05-07 17:29:22,821 WARN  L103        SettingsManager]:   * de.uni_freiburg.informatik.ultimate.core.Log level for class
[2025-05-07 17:29:22,834 INFO  L130        SettingsManager]: Preferences different from defaults after loading the file:
[2025-05-07 17:29:22,834 INFO  L151        SettingsManager]: Preferences of UltimateCore differ from their defaults:
[2025-05-07 17:29:22,835 INFO  L153        SettingsManager]:  * Log level for class=de.uni_freiburg.informatik.ultimate.lib.smtlibutils.quantifier.QuantifierPusher=ERROR;
[2025-05-07 17:29:22,835 INFO  L151        SettingsManager]: Preferences of Boogie Procedure Inliner differ from their defaults:
[2025-05-07 17:29:22,835 INFO  L153        SettingsManager]:  * Ignore calls to procedures called more than once=ONLY_FOR_SEQUENTIAL_PROGRAMS
[2025-05-07 17:29:22,835 INFO  L151        SettingsManager]: Preferences of BlockEncodingV2 differ from their defaults:
[2025-05-07 17:29:22,835 INFO  L153        SettingsManager]:  * Create parallel compositions if possible=false
[2025-05-07 17:29:22,835 INFO  L153        SettingsManager]:  * Use SBE=true
[2025-05-07 17:29:22,835 INFO  L151        SettingsManager]: Preferences of ChcToBoogie differ from their defaults:
[2025-05-07 17:29:22,835 INFO  L153        SettingsManager]:  * Use one large procedure with gotos to replace tail recursive calls=false
[2025-05-07 17:29:22,836 INFO  L151        SettingsManager]: Preferences of RCFGBuilder differ from their defaults:
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * Size of a code block=SequenceOfStatements
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * SMT solver=External_DefaultMode
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-05-07 17:29:22,836 INFO  L151        SettingsManager]: Preferences of TraceAbstraction differ from their defaults:
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * Compute Interpolants along a Counterexample=FPandBP
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * Trace refinement strategy=CAMEL
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * Command for external solver=z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * Automaton type used in concurrency analysis=PETRI_NET
[2025-05-07 17:29:22,836 INFO  L153        SettingsManager]:  * SMT solver=External_ModelsAndUnsatCoreMode
[2025-05-07 17:29:22,837 INFO  L151        SettingsManager]: Preferences of SmtParser differ from their defaults:
[2025-05-07 17:29:22,837 INFO  L153        SettingsManager]:  * SmtParser Mode=UltimateTreeAutomizer
[2025-05-07 17:29:23,014 INFO  L75    nceAwareModelManager]: Repository-Root is: /tmp
[2025-05-07 17:29:23,022 INFO  L261   ainManager$Toolchain]: [Toolchain 1]: Applicable parser(s) successfully (re)initialized
[2025-05-07 17:29:23,024 INFO  L217   ainManager$Toolchain]: [Toolchain 1]: Toolchain selected.
[2025-05-07 17:29:23,025 INFO  L270        PluginConnector]: Initializing SmtParser...
[2025-05-07 17:29:23,025 INFO  L274        PluginConnector]: SmtParser initialized
[2025-05-07 17:29:23,026 INFO  L431   ainManager$Toolchain]: [Toolchain 1]: Parsing single file: /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_bca83790-87e9-4477-9c67-d7afe36cffb5/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp25-benchmarks/solidity/no_adts/unit_tests/functions/virtual_function_called_by_constructor.sol_5_no_adts_000.smt2
[2025-05-07 17:29:23,028 INFO  L314              SmtParser]: Parsing .smt2 file as a set of Horn Clauses
[2025-05-07 17:29:23,031 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
unknown
[2025-05-07 17:29:23,266 INFO  L339              SmtParser]: Successfully executed SMT file /tmp/vcloud_worker_vcloud-master_on_vcloud-master/run_dir_bca83790-87e9-4477-9c67-d7afe36cffb5/chc-comp25-scripts/participants/UltimateUnihorn/../../../chc-comp25-benchmarks/solidity/no_adts/unit_tests/functions/virtual_function_called_by_constructor.sol_5_no_adts_000.smt2
[2025-05-07 17:29:23,268 INFO  L299   ainManager$Toolchain]: ####################### [Toolchain 1] #######################
[2025-05-07 17:29:23,269 INFO  L133        ToolchainWalker]: Walking toolchain with 4 elements.
[2025-05-07 17:29:23,270 INFO  L112        PluginConnector]: ------------------------ChcToBoogie----------------------------
[2025-05-07 17:29:23,270 INFO  L270        PluginConnector]: Initializing ChcToBoogie...
[2025-05-07 17:29:23,270 INFO  L274        PluginConnector]: ChcToBoogie initialized
[2025-05-07 17:29:23,272 INFO  L184        PluginConnector]: Executing the observer ChcToBoogieObserver from plugin ChcToBoogie for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,300 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23 Unit
[2025-05-07 17:29:23,301 INFO  L131        PluginConnector]: ------------------------ END ChcToBoogie----------------------------
[2025-05-07 17:29:23,302 INFO  L112        PluginConnector]: ------------------------Boogie Preprocessor----------------------------
[2025-05-07 17:29:23,302 INFO  L270        PluginConnector]: Initializing Boogie Preprocessor...
[2025-05-07 17:29:23,302 INFO  L274        PluginConnector]: Boogie Preprocessor initialized
[2025-05-07 17:29:23,314 INFO  L184        PluginConnector]: Executing the observer EnsureBoogieModelObserver from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,314 INFO  L184        PluginConnector]: Executing the observer TypeChecker from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,342 INFO  L184        PluginConnector]: Executing the observer ConstExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,342 INFO  L184        PluginConnector]: Executing the observer StructExpander from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,348 INFO  L184        PluginConnector]: Executing the observer ReplaceArrayAssignments from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,349 INFO  L184        PluginConnector]: Executing the observer UnstructureCode from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,351 INFO  L184        PluginConnector]: Executing the observer FunctionInliner from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,354 INFO  L184        PluginConnector]: Executing the observer LTLStepAnnotator from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,355 INFO  L184        PluginConnector]: Executing the observer BoogieSymbolTableConstructor from plugin Boogie Preprocessor for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,370 INFO  L131        PluginConnector]: ------------------------ END Boogie Preprocessor----------------------------
[2025-05-07 17:29:23,371 INFO  L112        PluginConnector]: ------------------------RCFGBuilder----------------------------
[2025-05-07 17:29:23,371 INFO  L270        PluginConnector]: Initializing RCFGBuilder...
[2025-05-07 17:29:23,371 INFO  L274        PluginConnector]: RCFGBuilder initialized
[2025-05-07 17:29:23,372 INFO  L184        PluginConnector]: Executing the observer RCFGBuilderObserver from plugin RCFGBuilder for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (1/1) ...
[2025-05-07 17:29:23,375 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000
[2025-05-07 17:29:23,383 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-05-07 17:29:23,391 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-05-07 17:29:23,393 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000 (1)] Waiting until timeout for monitored process
[2025-05-07 17:29:23,400 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure False given in one single declaration
[2025-05-07 17:29:23,400 INFO  L130     BoogieDeclarations]: Found specification of procedure False
[2025-05-07 17:29:23,400 INFO  L138     BoogieDeclarations]: Found implementation of procedure False
[2025-05-07 17:29:23,400 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure error_target_9_0 given in one single declaration
[2025-05-07 17:29:23,400 INFO  L130     BoogieDeclarations]: Found specification of procedure error_target_9_0
[2025-05-07 17:29:23,400 INFO  L138     BoogieDeclarations]: Found implementation of procedure error_target_9_0
[2025-05-07 17:29:23,400 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure summary_6_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,400 INFO  L130     BoogieDeclarations]: Found specification of procedure summary_6_function_i__33_34_0
[2025-05-07 17:29:23,400 INFO  L138     BoogieDeclarations]: Found implementation of procedure summary_6_function_i__33_34_0
[2025-05-07 17:29:23,400 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_24_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,400 INFO  L130     BoogieDeclarations]: Found specification of procedure block_24_function_i__33_34_0
[2025-05-07 17:29:23,400 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_24_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure summary_5_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,401 INFO  L130     BoogieDeclarations]: Found specification of procedure summary_5_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L138     BoogieDeclarations]: Found implementation of procedure summary_5_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_21_return_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,401 INFO  L130     BoogieDeclarations]: Found specification of procedure block_21_return_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_21_return_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_20_i_32_34_0 given in one single declaration
[2025-05-07 17:29:23,401 INFO  L130     BoogieDeclarations]: Found specification of procedure block_20_i_32_34_0
[2025-05-07 17:29:23,401 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_20_i_32_34_0
[2025-05-07 17:29:23,401 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_19_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,401 INFO  L130     BoogieDeclarations]: Found specification of procedure block_19_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_19_function_i__33_34_0
[2025-05-07 17:29:23,401 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_23_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,401 INFO  L130     BoogieDeclarations]: Found specification of procedure block_23_function_i__33_34_0
[2025-05-07 17:29:23,402 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_23_function_i__33_34_0
[2025-05-07 17:29:23,402 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_22_function_i__33_34_0 given in one single declaration
[2025-05-07 17:29:23,402 INFO  L130     BoogieDeclarations]: Found specification of procedure block_22_function_i__33_34_0
[2025-05-07 17:29:23,402 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_22_function_i__33_34_0
[2025-05-07 17:29:23,402 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure interface_0_A_34_0 given in one single declaration
[2025-05-07 17:29:23,402 INFO  L130     BoogieDeclarations]: Found specification of procedure interface_0_A_34_0
[2025-05-07 17:29:23,402 INFO  L138     BoogieDeclarations]: Found implementation of procedure interface_0_A_34_0
[2025-05-07 17:29:23,402 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure summary_constructor_2_A_34_0 given in one single declaration
[2025-05-07 17:29:23,402 INFO  L130     BoogieDeclarations]: Found specification of procedure summary_constructor_2_A_34_0
[2025-05-07 17:29:23,402 INFO  L138     BoogieDeclarations]: Found implementation of procedure summary_constructor_2_A_34_0
[2025-05-07 17:29:23,402 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure implicit_constructor_entry_32_A_34_0 given in one single declaration
[2025-05-07 17:29:23,402 INFO  L130     BoogieDeclarations]: Found specification of procedure implicit_constructor_entry_32_A_34_0
[2025-05-07 17:29:23,402 INFO  L138     BoogieDeclarations]: Found implementation of procedure implicit_constructor_entry_32_A_34_0
[2025-05-07 17:29:23,402 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure contract_initializer_29_A_34_0 given in one single declaration
[2025-05-07 17:29:23,402 INFO  L130     BoogieDeclarations]: Found specification of procedure contract_initializer_29_A_34_0
[2025-05-07 17:29:23,402 INFO  L138     BoogieDeclarations]: Found implementation of procedure contract_initializer_29_A_34_0
[2025-05-07 17:29:23,402 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure contract_initializer_after_init_31_A_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure contract_initializer_after_init_31_A_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure contract_initializer_after_init_31_A_34_0
[2025-05-07 17:29:23,403 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure contract_initializer_entry_30_A_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure contract_initializer_entry_30_A_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure contract_initializer_entry_30_A_34_0
[2025-05-07 17:29:23,403 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure summary_4_constructor_17_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure summary_4_constructor_17_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure summary_4_constructor_17_34_0
[2025-05-07 17:29:23,403 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_27_return_constructor_17_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure block_27_return_constructor_17_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_27_return_constructor_17_34_0
[2025-05-07 17:29:23,403 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure summary_28_function_v__10_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure summary_28_function_v__10_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure summary_28_function_v__10_34_0
[2025-05-07 17:29:23,403 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure summary_3_function_v__10_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure summary_3_function_v__10_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure summary_3_function_v__10_34_0
[2025-05-07 17:29:23,403 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_18_return_function_v__10_34_0 given in one single declaration
[2025-05-07 17:29:23,403 INFO  L130     BoogieDeclarations]: Found specification of procedure block_18_return_function_v__10_34_0
[2025-05-07 17:29:23,403 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_18_return_function_v__10_34_0
[2025-05-07 17:29:23,404 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_17_v_9_34_0 given in one single declaration
[2025-05-07 17:29:23,404 INFO  L130     BoogieDeclarations]: Found specification of procedure block_17_v_9_34_0
[2025-05-07 17:29:23,404 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_17_v_9_34_0
[2025-05-07 17:29:23,404 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_16_function_v__10_34_0 given in one single declaration
[2025-05-07 17:29:23,404 INFO  L130     BoogieDeclarations]: Found specification of procedure block_16_function_v__10_34_0
[2025-05-07 17:29:23,404 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_16_function_v__10_34_0
[2025-05-07 17:29:23,404 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_26__16_34_0 given in one single declaration
[2025-05-07 17:29:23,404 INFO  L130     BoogieDeclarations]: Found specification of procedure block_26__16_34_0
[2025-05-07 17:29:23,404 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_26__16_34_0
[2025-05-07 17:29:23,404 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure block_25_constructor_17_34_0 given in one single declaration
[2025-05-07 17:29:23,404 INFO  L130     BoogieDeclarations]: Found specification of procedure block_25_constructor_17_34_0
[2025-05-07 17:29:23,404 INFO  L138     BoogieDeclarations]: Found implementation of procedure block_25_constructor_17_34_0
[2025-05-07 17:29:23,404 INFO  L124     BoogieDeclarations]: Specification and implementation of procedure Ultimate.START given in one single declaration
[2025-05-07 17:29:23,404 INFO  L130     BoogieDeclarations]: Found specification of procedure Ultimate.START
[2025-05-07 17:29:23,404 INFO  L138     BoogieDeclarations]: Found implementation of procedure Ultimate.START
[2025-05-07 17:29:23,524 INFO  L234             CfgBuilder]: Building ICFG
[2025-05-07 17:29:23,526 INFO  L260             CfgBuilder]: Building CFG for each procedure with an implementation
[2025-05-07 17:29:23,737 INFO  L?                        ?]: Removed 177 outVars from TransFormulas that were not future-live.
[2025-05-07 17:29:23,737 INFO  L283             CfgBuilder]: Performing block encoding
[2025-05-07 17:29:23,745 INFO  L304             CfgBuilder]: Using library mode
[2025-05-07 17:29:23,745 INFO  L312             CfgBuilder]: Removed 12 assume(true) statements.
[2025-05-07 17:29:23,745 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 07.05 05:29:23 BoogieIcfgContainer
[2025-05-07 17:29:23,746 INFO  L131        PluginConnector]: ------------------------ END RCFGBuilder----------------------------
[2025-05-07 17:29:23,747 INFO  L112        PluginConnector]: ------------------------TraceAbstraction----------------------------
[2025-05-07 17:29:23,747 INFO  L270        PluginConnector]: Initializing TraceAbstraction...
[2025-05-07 17:29:23,749 INFO  L274        PluginConnector]: TraceAbstraction initialized
[2025-05-07 17:29:23,750 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.source.smtparser OTHER 07.05 05:29:23" (1/3) ...
[2025-05-07 17:29:23,750 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@493ad0ba and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction OTHER 07.05 05:29:23, skipping insertion in model container
[2025-05-07 17:29:23,750 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.chctoboogie AST 07.05 05:29:23" (2/3) ...
[2025-05-07 17:29:23,750 INFO  L204        PluginConnector]: Invalid model from TraceAbstraction for observer de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction.TraceAbstractionObserver@493ad0ba and model type de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction AST 07.05 05:29:23, skipping insertion in model container
[2025-05-07 17:29:23,750 INFO  L184        PluginConnector]: Executing the observer TraceAbstractionObserver from plugin TraceAbstraction for "de.uni_freiburg.informatik.ultimate.plugins.generator.rcfgbuilder CFG 07.05 05:29:23" (3/3) ...
[2025-05-07 17:29:23,751 INFO  L128   eAbstractionObserver]: Analyzing ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver
[2025-05-07 17:29:23,762 INFO  L217   ceAbstractionStarter]: Automizer settings: Hoare:None NWA Interpolation:FPandBP Determinization: PREDICATE_ABSTRACTION
[2025-05-07 17:29:23,763 INFO  L151   ceAbstractionStarter]: Applying trace abstraction to ICFG de.uni_freiburg.informatik.ultimate.plugins.chctoboogie.ChcToBoogieObserver that has 26 procedures, 102 locations, 152 edges, 26 initial locations, 0 loop locations, and 1 error locations.
[2025-05-07 17:29:23,792 INFO  L339      AbstractCegarLoop]: ======== Iteration 0 == of CEGAR loop == AllErrorsAtOnce ========
[2025-05-07 17:29:23,800 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;@43d497d2, mLbeIndependenceSettings=[IndependenceType=SEMANTIC, AbstractionType=NONE, UseConditional=false, UseSemiCommutativity=true, Solver=Z3, SolverTimeout=1000ms]
[2025-05-07 17:29:23,800 INFO  L341      AbstractCegarLoop]: Starting to check reachability of 1 error locations.
[2025-05-07 17:29:23,803 INFO  L278                IsEmpty]: Start isEmpty. Operand  has 102 states, 43 states have (on average 1.0930232558139534) internal successors, (47), 47 states have internal predecessors, (47), 33 states have call successors, (35), 25 states have call predecessors, (35), 25 states have return successors, (35), 29 states have call predecessors, (35), 33 states have call successors, (35)
[2025-05-07 17:29:23,810 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 81
[2025-05-07 17:29:23,810 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:23,810 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, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:23,810 INFO  L403      AbstractCegarLoop]: === Iteration 1 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:23,814 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:23,814 INFO  L85        PathProgramCache]: Analyzing trace with hash -1045872583, now seen corresponding path program 1 times
[2025-05-07 17:29:23,819 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:23,819 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [290472383]
[2025-05-07 17:29:23,819 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:23,820 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:23,877 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 80 statements into 1 equivalence classes.
[2025-05-07 17:29:23,921 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 80 of 80 statements.
[2025-05-07 17:29:23,921 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:23,921 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:24,121 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-05-07 17:29:24,121 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:24,122 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [290472383]
[2025-05-07 17:29:24,122 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [290472383] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:24,122 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-05-07 17:29:24,124 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [4] imperfect sequences [] total 4
[2025-05-07 17:29:24,125 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [684296508]
[2025-05-07 17:29:24,126 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:24,128 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 4 states
[2025-05-07 17:29:24,129 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:24,158 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 4 interpolants.
[2025-05-07 17:29:24,158 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=5, Invalid=7, Unknown=0, NotChecked=0, Total=12
[2025-05-07 17:29:24,160 INFO  L87              Difference]: Start difference. First operand  has 102 states, 43 states have (on average 1.0930232558139534) internal successors, (47), 47 states have internal predecessors, (47), 33 states have call successors, (35), 25 states have call predecessors, (35), 25 states have return successors, (35), 29 states have call predecessors, (35), 33 states have call successors, (35) Second operand  has 4 states, 3 states have (on average 12.0) internal successors, (36), 4 states have internal predecessors, (36), 4 states have call successors, (22), 1 states have call predecessors, (22), 3 states have return successors, (22), 3 states have call predecessors, (22), 4 states have call successors, (22)
[2025-05-07 17:29:24,237 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:24,237 INFO  L93              Difference]: Finished difference Result 104 states and 120 transitions.
[2025-05-07 17:29:24,239 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 4 states. 
[2025-05-07 17:29:24,241 INFO  L79                 Accepts]: Start accepts. Automaton has  has 4 states, 3 states have (on average 12.0) internal successors, (36), 4 states have internal predecessors, (36), 4 states have call successors, (22), 1 states have call predecessors, (22), 3 states have return successors, (22), 3 states have call predecessors, (22), 4 states have call successors, (22) Word has length 80
[2025-05-07 17:29:24,241 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:24,249 INFO  L225             Difference]: With dead ends: 104
[2025-05-07 17:29:24,249 INFO  L226             Difference]: Without dead ends: 104
[2025-05-07 17:29:24,251 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-05-07 17:29:24,254 INFO  L437           NwaCegarLoop]: 112 mSDtfsCounter, 1 mSDsluCounter, 213 mSDsCounter, 0 mSdLazyCounter, 14 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 3 SdHoareTripleChecker+Valid, 325 SdHoareTripleChecker+Invalid, 14 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 14 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.0s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:24,254 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [3 Valid, 325 Invalid, 14 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 14 Invalid, 0 Unknown, 0 Unchecked, 0.0s Time]
[2025-05-07 17:29:24,265 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 104 states.
[2025-05-07 17:29:24,283 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 104 to 102.
[2025-05-07 17:29:24,284 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 102 states, 43 states have (on average 1.069767441860465) internal successors, (46), 46 states have internal predecessors, (46), 33 states have call successors, (35), 25 states have call predecessors, (35), 26 states have return successors, (36), 30 states have call predecessors, (36), 33 states have call successors, (36)
[2025-05-07 17:29:24,287 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 102 states to 102 states and 117 transitions.
[2025-05-07 17:29:24,287 INFO  L79                 Accepts]: Start accepts. Automaton has 102 states and 117 transitions. Word has length 80
[2025-05-07 17:29:24,288 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:24,288 INFO  L485      AbstractCegarLoop]: Abstraction has 102 states and 117 transitions.
[2025-05-07 17:29:24,288 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 4 states, 3 states have (on average 12.0) internal successors, (36), 4 states have internal predecessors, (36), 4 states have call successors, (22), 1 states have call predecessors, (22), 3 states have return successors, (22), 3 states have call predecessors, (22), 4 states have call successors, (22)
[2025-05-07 17:29:24,288 INFO  L278                IsEmpty]: Start isEmpty. Operand 102 states and 117 transitions.
[2025-05-07 17:29:24,290 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 84
[2025-05-07 17:29:24,290 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:24,290 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, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:24,291 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable1
[2025-05-07 17:29:24,291 INFO  L403      AbstractCegarLoop]: === Iteration 2 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:24,291 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:24,291 INFO  L85        PathProgramCache]: Analyzing trace with hash 1008324961, now seen corresponding path program 1 times
[2025-05-07 17:29:24,291 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:24,291 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1197779919]
[2025-05-07 17:29:24,291 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:24,292 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:24,339 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 83 statements into 1 equivalence classes.
[2025-05-07 17:29:24,347 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 83 of 83 statements.
[2025-05-07 17:29:24,347 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:24,347 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:24,496 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-05-07 17:29:24,496 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:24,496 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1197779919]
[2025-05-07 17:29:24,496 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1197779919] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:24,496 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-05-07 17:29:24,496 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [4] imperfect sequences [] total 4
[2025-05-07 17:29:24,496 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [1385924559]
[2025-05-07 17:29:24,496 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:24,497 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 4 states
[2025-05-07 17:29:24,498 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:24,498 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 4 interpolants.
[2025-05-07 17:29:24,498 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=5, Invalid=7, Unknown=0, NotChecked=0, Total=12
[2025-05-07 17:29:24,499 INFO  L87              Difference]: Start difference. First operand 102 states and 117 transitions. Second operand  has 4 states, 3 states have (on average 12.333333333333334) internal successors, (37), 4 states have internal predecessors, (37), 4 states have call successors, (23), 1 states have call predecessors, (23), 3 states have return successors, (23), 3 states have call predecessors, (23), 4 states have call successors, (23)
[2025-05-07 17:29:24,514 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:24,514 INFO  L93              Difference]: Finished difference Result 106 states and 121 transitions.
[2025-05-07 17:29:24,514 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 4 states. 
[2025-05-07 17:29:24,515 INFO  L79                 Accepts]: Start accepts. Automaton has  has 4 states, 3 states have (on average 12.333333333333334) internal successors, (37), 4 states have internal predecessors, (37), 4 states have call successors, (23), 1 states have call predecessors, (23), 3 states have return successors, (23), 3 states have call predecessors, (23), 4 states have call successors, (23) Word has length 83
[2025-05-07 17:29:24,515 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:24,516 INFO  L225             Difference]: With dead ends: 106
[2025-05-07 17:29:24,516 INFO  L226             Difference]: Without dead ends: 102
[2025-05-07 17:29:24,517 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-05-07 17:29:24,517 INFO  L437           NwaCegarLoop]: 116 mSDtfsCounter, 1 mSDsluCounter, 224 mSDsCounter, 0 mSdLazyCounter, 2 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 2 SdHoareTripleChecker+Valid, 340 SdHoareTripleChecker+Invalid, 2 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 2 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.0s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:24,517 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [2 Valid, 340 Invalid, 2 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 2 Invalid, 0 Unknown, 0 Unchecked, 0.0s Time]
[2025-05-07 17:29:24,518 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 102 states.
[2025-05-07 17:29:24,525 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 102 to 100.
[2025-05-07 17:29:24,525 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 100 states, 43 states have (on average 1.0465116279069768) internal successors, (45), 45 states have internal predecessors, (45), 31 states have call successors, (33), 25 states have call predecessors, (33), 26 states have return successors, (34), 29 states have call predecessors, (34), 31 states have call successors, (34)
[2025-05-07 17:29:24,527 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 100 states to 100 states and 112 transitions.
[2025-05-07 17:29:24,527 INFO  L79                 Accepts]: Start accepts. Automaton has 100 states and 112 transitions. Word has length 83
[2025-05-07 17:29:24,527 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:24,527 INFO  L485      AbstractCegarLoop]: Abstraction has 100 states and 112 transitions.
[2025-05-07 17:29:24,527 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 4 states, 3 states have (on average 12.333333333333334) internal successors, (37), 4 states have internal predecessors, (37), 4 states have call successors, (23), 1 states have call predecessors, (23), 3 states have return successors, (23), 3 states have call predecessors, (23), 4 states have call successors, (23)
[2025-05-07 17:29:24,528 INFO  L278                IsEmpty]: Start isEmpty. Operand 100 states and 112 transitions.
[2025-05-07 17:29:24,529 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 81
[2025-05-07 17:29:24,529 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:24,530 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, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:24,530 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable2
[2025-05-07 17:29:24,530 INFO  L403      AbstractCegarLoop]: === Iteration 3 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:24,530 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:24,530 INFO  L85        PathProgramCache]: Analyzing trace with hash -1626826793, now seen corresponding path program 1 times
[2025-05-07 17:29:24,530 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:24,531 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1641720864]
[2025-05-07 17:29:24,531 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:24,531 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:24,565 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 80 statements into 1 equivalence classes.
[2025-05-07 17:29:24,574 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 80 of 80 statements.
[2025-05-07 17:29:24,574 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:24,574 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:24,642 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-05-07 17:29:24,642 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:24,642 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1641720864]
[2025-05-07 17:29:24,642 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1641720864] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:24,643 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-05-07 17:29:24,643 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [4] imperfect sequences [] total 4
[2025-05-07 17:29:24,643 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [1697292148]
[2025-05-07 17:29:24,643 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:24,643 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 4 states
[2025-05-07 17:29:24,643 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:24,644 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 4 interpolants.
[2025-05-07 17:29:24,644 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=5, Invalid=7, Unknown=0, NotChecked=0, Total=12
[2025-05-07 17:29:24,644 INFO  L87              Difference]: Start difference. First operand 100 states and 112 transitions. Second operand  has 4 states, 3 states have (on average 12.0) internal successors, (36), 4 states have internal predecessors, (36), 4 states have call successors, (22), 1 states have call predecessors, (22), 3 states have return successors, (22), 3 states have call predecessors, (22), 4 states have call successors, (22)
[2025-05-07 17:29:24,666 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:24,666 INFO  L93              Difference]: Finished difference Result 102 states and 113 transitions.
[2025-05-07 17:29:24,666 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 4 states. 
[2025-05-07 17:29:24,667 INFO  L79                 Accepts]: Start accepts. Automaton has  has 4 states, 3 states have (on average 12.0) internal successors, (36), 4 states have internal predecessors, (36), 4 states have call successors, (22), 1 states have call predecessors, (22), 3 states have return successors, (22), 3 states have call predecessors, (22), 4 states have call successors, (22) Word has length 80
[2025-05-07 17:29:24,667 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:24,668 INFO  L225             Difference]: With dead ends: 102
[2025-05-07 17:29:24,668 INFO  L226             Difference]: Without dead ends: 94
[2025-05-07 17:29:24,668 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-05-07 17:29:24,669 INFO  L437           NwaCegarLoop]: 109 mSDtfsCounter, 1 mSDsluCounter, 208 mSDsCounter, 0 mSdLazyCounter, 6 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 3 SdHoareTripleChecker+Valid, 317 SdHoareTripleChecker+Invalid, 6 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 6 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.0s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:24,669 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [3 Valid, 317 Invalid, 6 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 6 Invalid, 0 Unknown, 0 Unchecked, 0.0s Time]
[2025-05-07 17:29:24,670 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 94 states.
[2025-05-07 17:29:24,674 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 94 to 94.
[2025-05-07 17:29:24,674 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 94 states, 41 states have (on average 1.024390243902439) internal successors, (42), 42 states have internal predecessors, (42), 27 states have call successors, (29), 25 states have call predecessors, (29), 25 states have return successors, (29), 26 states have call predecessors, (29), 27 states have call successors, (29)
[2025-05-07 17:29:24,675 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 94 states to 94 states and 100 transitions.
[2025-05-07 17:29:24,676 INFO  L79                 Accepts]: Start accepts. Automaton has 94 states and 100 transitions. Word has length 80
[2025-05-07 17:29:24,676 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:24,676 INFO  L485      AbstractCegarLoop]: Abstraction has 94 states and 100 transitions.
[2025-05-07 17:29:24,676 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 4 states, 3 states have (on average 12.0) internal successors, (36), 4 states have internal predecessors, (36), 4 states have call successors, (22), 1 states have call predecessors, (22), 3 states have return successors, (22), 3 states have call predecessors, (22), 4 states have call successors, (22)
[2025-05-07 17:29:24,676 INFO  L278                IsEmpty]: Start isEmpty. Operand 94 states and 100 transitions.
[2025-05-07 17:29:24,677 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 84
[2025-05-07 17:29:24,677 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:24,677 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, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:24,677 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable3
[2025-05-07 17:29:24,677 INFO  L403      AbstractCegarLoop]: === Iteration 4 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:24,678 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:24,678 INFO  L85        PathProgramCache]: Analyzing trace with hash 1291710813, now seen corresponding path program 1 times
[2025-05-07 17:29:24,678 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:24,678 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [1622972373]
[2025-05-07 17:29:24,678 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:24,678 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:24,712 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 83 statements into 1 equivalence classes.
[2025-05-07 17:29:24,741 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 83 of 83 statements.
[2025-05-07 17:29:24,741 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:24,742 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:24,877 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-05-07 17:29:24,877 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:24,878 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [1622972373]
[2025-05-07 17:29:24,878 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [1622972373] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:24,878 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-05-07 17:29:24,878 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [8] imperfect sequences [] total 8
[2025-05-07 17:29:24,878 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [347777560]
[2025-05-07 17:29:24,878 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:24,878 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 8 states
[2025-05-07 17:29:24,879 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:24,879 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 8 interpolants.
[2025-05-07 17:29:24,879 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=13, Invalid=43, Unknown=0, NotChecked=0, Total=56
[2025-05-07 17:29:24,880 INFO  L87              Difference]: Start difference. First operand 94 states and 100 transitions. Second operand  has 8 states, 6 states have (on average 6.166666666666667) internal successors, (37), 8 states have internal predecessors, (37), 5 states have call successors, (23), 1 states have call predecessors, (23), 6 states have return successors, (23), 7 states have call predecessors, (23), 5 states have call successors, (23)
[2025-05-07 17:29:24,959 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:24,960 INFO  L93              Difference]: Finished difference Result 99 states and 106 transitions.
[2025-05-07 17:29:24,960 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 8 states. 
[2025-05-07 17:29:24,960 INFO  L79                 Accepts]: Start accepts. Automaton has  has 8 states, 6 states have (on average 6.166666666666667) internal successors, (37), 8 states have internal predecessors, (37), 5 states have call successors, (23), 1 states have call predecessors, (23), 6 states have return successors, (23), 7 states have call predecessors, (23), 5 states have call successors, (23) Word has length 83
[2025-05-07 17:29:24,961 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:24,962 INFO  L225             Difference]: With dead ends: 99
[2025-05-07 17:29:24,962 INFO  L226             Difference]: Without dead ends: 99
[2025-05-07 17:29:24,962 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 9 GetRequests, 2 SyntacticMatches, 0 SemanticMatches, 7 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 0 ImplicationChecksByTransitivity, 0.0s TimeCoverageRelationStatistics Valid=17, Invalid=55, Unknown=0, NotChecked=0, Total=72
[2025-05-07 17:29:24,963 INFO  L437           NwaCegarLoop]: 97 mSDtfsCounter, 2 mSDsluCounter, 562 mSDsCounter, 0 mSdLazyCounter, 47 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 4 SdHoareTripleChecker+Valid, 659 SdHoareTripleChecker+Invalid, 47 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 47 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.1s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:24,963 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [4 Valid, 659 Invalid, 47 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 47 Invalid, 0 Unknown, 0 Unchecked, 0.1s Time]
[2025-05-07 17:29:24,964 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 99 states.
[2025-05-07 17:29:24,968 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 99 to 99.
[2025-05-07 17:29:24,969 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 99 states, 43 states have (on average 1.0232558139534884) internal successors, (44), 44 states have internal predecessors, (44), 28 states have call successors, (30), 25 states have call predecessors, (30), 27 states have return successors, (32), 29 states have call predecessors, (32), 28 states have call successors, (32)
[2025-05-07 17:29:24,970 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 99 states to 99 states and 106 transitions.
[2025-05-07 17:29:24,970 INFO  L79                 Accepts]: Start accepts. Automaton has 99 states and 106 transitions. Word has length 83
[2025-05-07 17:29:24,970 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:24,971 INFO  L485      AbstractCegarLoop]: Abstraction has 99 states and 106 transitions.
[2025-05-07 17:29:24,971 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 8 states, 6 states have (on average 6.166666666666667) internal successors, (37), 8 states have internal predecessors, (37), 5 states have call successors, (23), 1 states have call predecessors, (23), 6 states have return successors, (23), 7 states have call predecessors, (23), 5 states have call successors, (23)
[2025-05-07 17:29:24,971 INFO  L278                IsEmpty]: Start isEmpty. Operand 99 states and 106 transitions.
[2025-05-07 17:29:24,972 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 84
[2025-05-07 17:29:24,972 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:24,972 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, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:24,972 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable4
[2025-05-07 17:29:24,972 INFO  L403      AbstractCegarLoop]: === Iteration 5 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:24,973 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:24,973 INFO  L85        PathProgramCache]: Analyzing trace with hash 107269843, now seen corresponding path program 1 times
[2025-05-07 17:29:24,973 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:24,973 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [347690772]
[2025-05-07 17:29:24,973 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:24,973 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:25,005 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 83 statements into 1 equivalence classes.
[2025-05-07 17:29:25,025 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 83 of 83 statements.
[2025-05-07 17:29:25,025 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:25,025 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:25,462 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-05-07 17:29:25,462 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:25,462 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [347690772]
[2025-05-07 17:29:25,463 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [347690772] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:25,463 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-05-07 17:29:25,463 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [17] imperfect sequences [] total 17
[2025-05-07 17:29:25,463 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [808778571]
[2025-05-07 17:29:25,463 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:25,463 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 17 states
[2025-05-07 17:29:25,463 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:25,464 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 17 interpolants.
[2025-05-07 17:29:25,464 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=31, Invalid=241, Unknown=0, NotChecked=0, Total=272
[2025-05-07 17:29:25,465 INFO  L87              Difference]: Start difference. First operand 99 states and 106 transitions. Second operand  has 17 states, 14 states have (on average 2.642857142857143) internal successors, (37), 16 states have internal predecessors, (37), 9 states have call successors, (23), 1 states have call predecessors, (23), 14 states have return successors, (23), 15 states have call predecessors, (23), 9 states have call successors, (23)
[2025-05-07 17:29:25,718 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:25,718 INFO  L93              Difference]: Finished difference Result 109 states and 120 transitions.
[2025-05-07 17:29:25,719 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 17 states. 
[2025-05-07 17:29:25,719 INFO  L79                 Accepts]: Start accepts. Automaton has  has 17 states, 14 states have (on average 2.642857142857143) internal successors, (37), 16 states have internal predecessors, (37), 9 states have call successors, (23), 1 states have call predecessors, (23), 14 states have return successors, (23), 15 states have call predecessors, (23), 9 states have call successors, (23) Word has length 83
[2025-05-07 17:29:25,719 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:25,720 INFO  L225             Difference]: With dead ends: 109
[2025-05-07 17:29:25,720 INFO  L226             Difference]: Without dead ends: 109
[2025-05-07 17:29:25,720 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 19 GetRequests, 2 SyntacticMatches, 0 SemanticMatches, 17 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 1 ImplicationChecksByTransitivity, 0.1s TimeCoverageRelationStatistics Valid=39, Invalid=303, Unknown=0, NotChecked=0, Total=342
[2025-05-07 17:29:25,720 INFO  L437           NwaCegarLoop]: 84 mSDtfsCounter, 4 mSDsluCounter, 1220 mSDsCounter, 0 mSdLazyCounter, 281 mSolverCounterSat, 1 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.2s Time, 0 mProtectedPredicate, 0 mProtectedAction, 9 SdHoareTripleChecker+Valid, 1304 SdHoareTripleChecker+Invalid, 282 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 1 IncrementalHoareTripleChecker+Valid, 281 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.2s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:25,721 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [9 Valid, 1304 Invalid, 282 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [1 Valid, 281 Invalid, 0 Unknown, 0 Unchecked, 0.2s Time]
[2025-05-07 17:29:25,721 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 109 states.
[2025-05-07 17:29:25,726 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 109 to 107.
[2025-05-07 17:29:25,726 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 107 states, 46 states have (on average 1.0217391304347827) internal successors, (47), 47 states have internal predecessors, (47), 30 states have call successors, (32), 25 states have call predecessors, (32), 30 states have return successors, (39), 34 states have call predecessors, (39), 30 states have call successors, (39)
[2025-05-07 17:29:25,727 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 107 states to 107 states and 118 transitions.
[2025-05-07 17:29:25,727 INFO  L79                 Accepts]: Start accepts. Automaton has 107 states and 118 transitions. Word has length 83
[2025-05-07 17:29:25,727 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:25,727 INFO  L485      AbstractCegarLoop]: Abstraction has 107 states and 118 transitions.
[2025-05-07 17:29:25,727 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 17 states, 14 states have (on average 2.642857142857143) internal successors, (37), 16 states have internal predecessors, (37), 9 states have call successors, (23), 1 states have call predecessors, (23), 14 states have return successors, (23), 15 states have call predecessors, (23), 9 states have call successors, (23)
[2025-05-07 17:29:25,727 INFO  L278                IsEmpty]: Start isEmpty. Operand 107 states and 118 transitions.
[2025-05-07 17:29:25,728 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 84
[2025-05-07 17:29:25,728 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:25,728 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, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:25,728 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable5
[2025-05-07 17:29:25,728 INFO  L403      AbstractCegarLoop]: === Iteration 6 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:25,729 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:25,729 INFO  L85        PathProgramCache]: Analyzing trace with hash -187766589, now seen corresponding path program 1 times
[2025-05-07 17:29:25,729 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:25,729 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [296168414]
[2025-05-07 17:29:25,729 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:25,729 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:25,751 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 83 statements into 1 equivalence classes.
[2025-05-07 17:29:25,762 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 83 of 83 statements.
[2025-05-07 17:29:25,762 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:25,762 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:25,856 INFO  L134       CoverageAnalysis]: Checked inductivity of 0 backedges. 0 proven. 0 refuted. 0 times theorem prover too weak. 0 trivial. 0 not checked.
[2025-05-07 17:29:25,856 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:25,856 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [296168414]
[2025-05-07 17:29:25,856 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [296168414] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:25,856 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 0 imperfect interpolant sequences.
[2025-05-07 17:29:25,856 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [6] imperfect sequences [] total 6
[2025-05-07 17:29:25,857 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [67339744]
[2025-05-07 17:29:25,857 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:25,857 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 6 states
[2025-05-07 17:29:25,857 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:25,858 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 6 interpolants.
[2025-05-07 17:29:25,858 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=9, Invalid=21, Unknown=0, NotChecked=0, Total=30
[2025-05-07 17:29:25,858 INFO  L87              Difference]: Start difference. First operand 107 states and 118 transitions. Second operand  has 6 states, 5 states have (on average 7.4) internal successors, (37), 6 states have internal predecessors, (37), 4 states have call successors, (23), 1 states have call predecessors, (23), 5 states have return successors, (23), 6 states have call predecessors, (23), 4 states have call successors, (23)
[2025-05-07 17:29:25,887 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:25,887 INFO  L93              Difference]: Finished difference Result 107 states and 116 transitions.
[2025-05-07 17:29:25,887 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 6 states. 
[2025-05-07 17:29:25,888 INFO  L79                 Accepts]: Start accepts. Automaton has  has 6 states, 5 states have (on average 7.4) internal successors, (37), 6 states have internal predecessors, (37), 4 states have call successors, (23), 1 states have call predecessors, (23), 5 states have return successors, (23), 6 states have call predecessors, (23), 4 states have call successors, (23) Word has length 83
[2025-05-07 17:29:25,888 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:25,889 INFO  L225             Difference]: With dead ends: 107
[2025-05-07 17:29:25,889 INFO  L226             Difference]: Without dead ends: 106
[2025-05-07 17:29:25,889 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 6 GetRequests, 2 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-05-07 17:29:25,889 INFO  L437           NwaCegarLoop]: 102 mSDtfsCounter, 1 mSDsluCounter, 394 mSDsCounter, 0 mSdLazyCounter, 17 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 2 SdHoareTripleChecker+Valid, 496 SdHoareTripleChecker+Invalid, 17 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 17 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.0s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:25,890 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [2 Valid, 496 Invalid, 17 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 17 Invalid, 0 Unknown, 0 Unchecked, 0.0s Time]
[2025-05-07 17:29:25,890 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 106 states.
[2025-05-07 17:29:25,894 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 106 to 101.
[2025-05-07 17:29:25,894 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 101 states, 44 states have (on average 1.0227272727272727) internal successors, (45), 45 states have internal predecessors, (45), 28 states have call successors, (30), 25 states have call predecessors, (30), 28 states have return successors, (33), 30 states have call predecessors, (33), 28 states have call successors, (33)
[2025-05-07 17:29:25,895 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 101 states to 101 states and 108 transitions.
[2025-05-07 17:29:25,895 INFO  L79                 Accepts]: Start accepts. Automaton has 101 states and 108 transitions. Word has length 83
[2025-05-07 17:29:25,895 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:25,895 INFO  L485      AbstractCegarLoop]: Abstraction has 101 states and 108 transitions.
[2025-05-07 17:29:25,895 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 6 states, 5 states have (on average 7.4) internal successors, (37), 6 states have internal predecessors, (37), 4 states have call successors, (23), 1 states have call predecessors, (23), 5 states have return successors, (23), 6 states have call predecessors, (23), 4 states have call successors, (23)
[2025-05-07 17:29:25,895 INFO  L278                IsEmpty]: Start isEmpty. Operand 101 states and 108 transitions.
[2025-05-07 17:29:25,896 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 109
[2025-05-07 17:29:25,896 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:25,896 INFO  L218           NwaCegarLoop]: trace histogram [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:25,896 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable6
[2025-05-07 17:29:25,896 INFO  L403      AbstractCegarLoop]: === Iteration 7 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:25,896 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:25,896 INFO  L85        PathProgramCache]: Analyzing trace with hash 230625628, now seen corresponding path program 1 times
[2025-05-07 17:29:25,896 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:25,896 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [424594857]
[2025-05-07 17:29:25,897 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:25,897 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:25,935 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-05-07 17:29:25,950 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-05-07 17:29:25,950 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:25,950 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:26,512 INFO  L134       CoverageAnalysis]: Checked inductivity of 19 backedges. 0 proven. 5 refuted. 0 times theorem prover too weak. 14 trivial. 0 not checked.
[2025-05-07 17:29:26,512 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:26,512 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [424594857]
[2025-05-07 17:29:26,512 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [424594857] provided 0 perfect and 1 imperfect interpolant sequences
[2025-05-07 17:29:26,512 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [157326302]
[2025-05-07 17:29:26,512 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:26,512 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-05-07 17:29:26,515 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-05-07 17:29:26,517 INFO  L229       MonitoredProcess]: Starting monitored process 2 with /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (exit command is (exit), workingDir is null)
[2025-05-07 17:29:26,518 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Waiting until timeout for monitored process
[2025-05-07 17:29:26,763 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-05-07 17:29:26,942 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-05-07 17:29:26,942 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:26,942 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:26,948 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1436 conjuncts, 64 conjuncts are in the unsatisfiable core
[2025-05-07 17:29:26,959 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-05-07 17:29:27,062 INFO  L134       CoverageAnalysis]: Checked inductivity of 19 backedges. 0 proven. 5 refuted. 0 times theorem prover too weak. 14 trivial. 0 not checked.
[2025-05-07 17:29:27,062 INFO  L312         TraceCheckSpWp]: Computing backward predicates...
[2025-05-07 17:29:28,555 INFO  L134       CoverageAnalysis]: Checked inductivity of 19 backedges. 0 proven. 9 refuted. 0 times theorem prover too weak. 10 trivial. 0 not checked.
[2025-05-07 17:29:28,555 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [157326302] provided 0 perfect and 2 imperfect interpolant sequences
[2025-05-07 17:29:28,555 INFO  L185   FreeRefinementEngine]: Found 0 perfect and 3 imperfect interpolant sequences.
[2025-05-07 17:29:28,556 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [] imperfect sequences [21, 21, 23] total 30
[2025-05-07 17:29:28,556 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [594277523]
[2025-05-07 17:29:28,556 INFO  L85    oduleStraightlineAll]: Using 3 imperfect interpolants to construct interpolant automaton
[2025-05-07 17:29:28,556 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 30 states
[2025-05-07 17:29:28,556 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:28,557 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 30 interpolants.
[2025-05-07 17:29:28,557 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=72, Invalid=798, Unknown=0, NotChecked=0, Total=870
[2025-05-07 17:29:28,557 INFO  L87              Difference]: Start difference. First operand 101 states and 108 transitions. Second operand  has 30 states, 22 states have (on average 2.3636363636363638) internal successors, (52), 27 states have internal predecessors, (52), 18 states have call successors, (35), 1 states have call predecessors, (35), 22 states have return successors, (39), 25 states have call predecessors, (39), 18 states have call successors, (39)
[2025-05-07 17:29:28,853 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:28,853 INFO  L93              Difference]: Finished difference Result 112 states and 124 transitions.
[2025-05-07 17:29:28,853 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 22 states. 
[2025-05-07 17:29:28,853 INFO  L79                 Accepts]: Start accepts. Automaton has  has 30 states, 22 states have (on average 2.3636363636363638) internal successors, (52), 27 states have internal predecessors, (52), 18 states have call successors, (35), 1 states have call predecessors, (35), 22 states have return successors, (39), 25 states have call predecessors, (39), 18 states have call successors, (39) Word has length 108
[2025-05-07 17:29:28,854 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:28,854 INFO  L225             Difference]: With dead ends: 112
[2025-05-07 17:29:28,854 INFO  L226             Difference]: Without dead ends: 111
[2025-05-07 17:29:28,855 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 243 GetRequests, 211 SyntacticMatches, 0 SemanticMatches, 32 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 44 ImplicationChecksByTransitivity, 0.3s TimeCoverageRelationStatistics Valid=95, Invalid=1027, Unknown=0, NotChecked=0, Total=1122
[2025-05-07 17:29:28,855 INFO  L437           NwaCegarLoop]: 94 mSDtfsCounter, 5 mSDsluCounter, 1897 mSDsCounter, 0 mSdLazyCounter, 479 mSolverCounterSat, 6 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.2s Time, 0 mProtectedPredicate, 0 mProtectedAction, 10 SdHoareTripleChecker+Valid, 1991 SdHoareTripleChecker+Invalid, 485 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 6 IncrementalHoareTripleChecker+Valid, 479 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.2s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:28,855 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [10 Valid, 1991 Invalid, 485 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [6 Valid, 479 Invalid, 0 Unknown, 0 Unchecked, 0.2s Time]
[2025-05-07 17:29:28,856 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 111 states.
[2025-05-07 17:29:28,859 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 111 to 107.
[2025-05-07 17:29:28,859 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 107 states, 46 states have (on average 1.0217391304347827) internal successors, (47), 47 states have internal predecessors, (47), 30 states have call successors, (32), 25 states have call predecessors, (32), 30 states have return successors, (38), 34 states have call predecessors, (38), 30 states have call successors, (38)
[2025-05-07 17:29:28,860 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 107 states to 107 states and 117 transitions.
[2025-05-07 17:29:28,860 INFO  L79                 Accepts]: Start accepts. Automaton has 107 states and 117 transitions. Word has length 108
[2025-05-07 17:29:28,860 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:28,860 INFO  L485      AbstractCegarLoop]: Abstraction has 107 states and 117 transitions.
[2025-05-07 17:29:28,860 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 30 states, 22 states have (on average 2.3636363636363638) internal successors, (52), 27 states have internal predecessors, (52), 18 states have call successors, (35), 1 states have call predecessors, (35), 22 states have return successors, (39), 25 states have call predecessors, (39), 18 states have call successors, (39)
[2025-05-07 17:29:28,860 INFO  L278                IsEmpty]: Start isEmpty. Operand 107 states and 117 transitions.
[2025-05-07 17:29:28,861 INFO  L284                IsEmpty]: Finished isEmpty. Found accepting run of length 109
[2025-05-07 17:29:28,861 INFO  L210           NwaCegarLoop]: Found error trace
[2025-05-07 17:29:28,862 INFO  L218           NwaCegarLoop]: trace histogram [2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 2, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 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-05-07 17:29:28,867 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (2)] Ended with exit code 0
[2025-05-07 17:29:29,062 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: 2 /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true,SelfDestructingSolverStorable7
[2025-05-07 17:29:29,063 INFO  L403      AbstractCegarLoop]: === Iteration 8 === Targeting Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY === [Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY] ===
[2025-05-07 17:29:29,064 INFO  L155       PredicateUnifier]: Initialized classic predicate unifier
[2025-05-07 17:29:29,064 INFO  L85        PathProgramCache]: Analyzing trace with hash -2056837322, now seen corresponding path program 1 times
[2025-05-07 17:29:29,064 INFO  L118   FreeRefinementEngine]: Executing refinement strategy CAMEL
[2025-05-07 17:29:29,065 INFO  L324   FreeRefinementEngine]: Using trace check IpTcStrategyModuleSmtInterpolCraig [2063931033]
[2025-05-07 17:29:29,065 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:29,065 INFO  L127          SolverBuilder]: Constructing new instance of SMTInterpol with explicit timeout -1 ms and remaining time -1 ms
[2025-05-07 17:29:29,142 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-05-07 17:29:29,154 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-05-07 17:29:29,154 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:29,154 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:29,606 INFO  L134       CoverageAnalysis]: Checked inductivity of 19 backedges. 0 proven. 5 refuted. 0 times theorem prover too weak. 14 trivial. 0 not checked.
[2025-05-07 17:29:29,606 INFO  L136   FreeRefinementEngine]: Strategy CAMEL found an infeasible trace
[2025-05-07 17:29:29,606 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleSmtInterpolCraig [2063931033]
[2025-05-07 17:29:29,606 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleSmtInterpolCraig [2063931033] provided 0 perfect and 1 imperfect interpolant sequences
[2025-05-07 17:29:29,606 INFO  L324   FreeRefinementEngine]: Using interpolant generator IpTcStrategyModuleZ3 [775226780]
[2025-05-07 17:29:29,606 INFO  L97    rtionOrderModulation]: Keeping assertion order NOT_INCREMENTALLY
[2025-05-07 17:29:29,606 INFO  L173          SolverBuilder]: Constructing external solver with command: z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-05-07 17:29:29,609 INFO  L189       MonitoredProcess]: No working directory specified, using /usr/bin/z3
[2025-05-07 17:29:29,610 INFO  L229       MonitoredProcess]: Starting monitored process 3 with /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (exit command is (exit), workingDir is null)
[2025-05-07 17:29:29,611 INFO  L327       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Waiting until timeout for monitored process
[2025-05-07 17:29:29,830 INFO  L108    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY partitioned 108 statements into 1 equivalence classes.
[2025-05-07 17:29:29,940 INFO  L111    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s) and asserted 108 of 108 statements.
[2025-05-07 17:29:29,940 INFO  L114    AnnotateAndAsserter]: Assert order NOT_INCREMENTALLY issued 1 check-sat command(s)
[2025-05-07 17:29:29,940 INFO  L115    AnnotateAndAsserter]: Conjunction of SSA is unsat
[2025-05-07 17:29:29,945 INFO  L256         TraceCheckSpWp]: Trace formula consists of 1437 conjuncts, 5 conjuncts are in the unsatisfiable core
[2025-05-07 17:29:29,950 INFO  L279         TraceCheckSpWp]: Computing forward predicates...
[2025-05-07 17:29:30,030 INFO  L134       CoverageAnalysis]: Checked inductivity of 19 backedges. 18 proven. 0 refuted. 0 times theorem prover too weak. 1 trivial. 0 not checked.
[2025-05-07 17:29:30,030 INFO  L308         TraceCheckSpWp]: Omiting computation of backward sequence because forward sequence was already perfect
[2025-05-07 17:29:30,030 INFO  L158   FreeRefinementEngine]: IpTcStrategyModuleZ3 [775226780] provided 1 perfect and 0 imperfect interpolant sequences
[2025-05-07 17:29:30,030 INFO  L185   FreeRefinementEngine]: Found 1 perfect and 1 imperfect interpolant sequences.
[2025-05-07 17:29:30,031 INFO  L198   FreeRefinementEngine]: Number of different interpolants: perfect sequences [6] imperfect sequences [21] total 25
[2025-05-07 17:29:30,031 INFO  L121   tionRefinementEngine]: Using interpolant automaton builder IpAbStrategyModuleStraightlineAll [1734489431]
[2025-05-07 17:29:30,031 INFO  L85    oduleStraightlineAll]: Using 1 perfect interpolants to construct interpolant automaton
[2025-05-07 17:29:30,031 INFO  L562      AbstractCegarLoop]: INTERPOLANT automaton has 6 states
[2025-05-07 17:29:30,031 INFO  L100   FreeRefinementEngine]: Using predicate unifier PredicateUnifier provided by strategy CAMEL
[2025-05-07 17:29:30,032 INFO  L144   InterpolantAutomaton]: Constructing interpolant automaton starting with 6 interpolants.
[2025-05-07 17:29:30,032 INFO  L146   InterpolantAutomaton]: CoverageRelationStatistics Valid=47, Invalid=553, Unknown=0, NotChecked=0, Total=600
[2025-05-07 17:29:30,032 INFO  L87              Difference]: Start difference. First operand 107 states and 117 transitions. Second operand  has 6 states, 5 states have (on average 9.6) internal successors, (48), 6 states have internal predecessors, (48), 5 states have call successors, (30), 2 states have call predecessors, (30), 5 states have return successors, (30), 6 states have call predecessors, (30), 5 states have call successors, (30)
[2025-05-07 17:29:30,051 INFO  L144             Difference]: Subtrahend was deterministic. Have not used determinization.
[2025-05-07 17:29:30,051 INFO  L93              Difference]: Finished difference Result 99 states and 104 transitions.
[2025-05-07 17:29:30,052 INFO  L140   InterpolantAutomaton]: Switched to read-only mode: deterministic interpolant automaton has 6 states. 
[2025-05-07 17:29:30,052 INFO  L79                 Accepts]: Start accepts. Automaton has  has 6 states, 5 states have (on average 9.6) internal successors, (48), 6 states have internal predecessors, (48), 5 states have call successors, (30), 2 states have call predecessors, (30), 5 states have return successors, (30), 6 states have call predecessors, (30), 5 states have call successors, (30) Word has length 108
[2025-05-07 17:29:30,052 INFO  L85                 Accepts]: Finished accepts. some prefix is accepted.
[2025-05-07 17:29:30,052 INFO  L225             Difference]: With dead ends: 99
[2025-05-07 17:29:30,053 INFO  L226             Difference]: Without dead ends: 0
[2025-05-07 17:29:30,053 INFO  L436           NwaCegarLoop]: 0 DeclaredPredicates, 131 GetRequests, 108 SyntacticMatches, 0 SemanticMatches, 23 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 0 ImplicationChecksByTransitivity, 0.2s TimeCoverageRelationStatistics Valid=47, Invalid=553, Unknown=0, NotChecked=0, Total=600
[2025-05-07 17:29:30,054 INFO  L437           NwaCegarLoop]: 97 mSDtfsCounter, 1 mSDsluCounter, 373 mSDsCounter, 0 mSdLazyCounter, 12 mSolverCounterSat, 0 mSolverCounterUnsat, 0 mSolverCounterUnknown, 0 mSolverCounterNotChecked, 0.0s Time, 0 mProtectedPredicate, 0 mProtectedAction, 2 SdHoareTripleChecker+Valid, 470 SdHoareTripleChecker+Invalid, 12 SdHoareTripleChecker+Unknown, 0 SdHoareTripleChecker+Unchecked, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Valid, 12 IncrementalHoareTripleChecker+Invalid, 0 IncrementalHoareTripleChecker+Unknown, 0 IncrementalHoareTripleChecker+Unchecked, 0.0s IncrementalHoareTripleChecker+Time
[2025-05-07 17:29:30,054 INFO  L438           NwaCegarLoop]: SdHoareTripleChecker [2 Valid, 470 Invalid, 12 Unknown, 0 Unchecked, 0.0s Time], IncrementalHoareTripleChecker [0 Valid, 12 Invalid, 0 Unknown, 0 Unchecked, 0.0s Time]
[2025-05-07 17:29:30,054 INFO  L82        GeneralOperation]: Start minimizeSevpa. Operand 0 states.
[2025-05-07 17:29:30,054 INFO  L88        GeneralOperation]: Finished minimizeSevpa. Reduced states from 0 to 0.
[2025-05-07 17:29:30,054 INFO  L82        GeneralOperation]: Start removeUnreachable. Operand  has 0 states, 0 states have (on average 0.0) internal successors, (0), 0 states have internal predecessors, (0), 0 states have call successors, (0), 0 states have call predecessors, (0), 0 states have return successors, (0), 0 states have call predecessors, (0), 0 states have call successors, (0)
[2025-05-07 17:29:30,055 INFO  L88        GeneralOperation]: Finished removeUnreachable. Reduced from 0 states to 0 states and 0 transitions.
[2025-05-07 17:29:30,055 INFO  L79                 Accepts]: Start accepts. Automaton has 0 states and 0 transitions. Word has length 108
[2025-05-07 17:29:30,055 INFO  L85                 Accepts]: Finished accepts. word is rejected.
[2025-05-07 17:29:30,055 INFO  L485      AbstractCegarLoop]: Abstraction has 0 states and 0 transitions.
[2025-05-07 17:29:30,055 INFO  L486      AbstractCegarLoop]: INTERPOLANT automaton has  has 6 states, 5 states have (on average 9.6) internal successors, (48), 6 states have internal predecessors, (48), 5 states have call successors, (30), 2 states have call predecessors, (30), 5 states have return successors, (30), 6 states have call predecessors, (30), 5 states have call successors, (30)
[2025-05-07 17:29:30,055 INFO  L278                IsEmpty]: Start isEmpty. Operand 0 states and 0 transitions.
[2025-05-07 17:29:30,055 INFO  L284                IsEmpty]: Finished isEmpty. No accepting run.
[2025-05-07 17:29:30,057 INFO  L790   garLoopResultBuilder]: Registering result SAFE for location Ultimate.STARTErr0ASSERT_VIOLATIONCHC_SATISFIABILITY (0 of 1 remaining)
[2025-05-07 17:29:30,063 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true (3)] Ended with exit code 0
[2025-05-07 17:29:30,258 WARN  L466      AbstractCegarLoop]: Destroyed unattended storables created during the last iteration: SelfDestructingSolverStorable8,3 /usr/bin/z3 -smt2 -in SMTLIB2_COMPLIANT=true
[2025-05-07 17:29:30,267 INFO  L422         BasicCegarLoop]: Path program histogram: [1, 1, 1, 1, 1, 1, 1, 1]
[2025-05-07 17:29:30,272 INFO  L171   ceAbstractionStarter]: Computing trace abstraction results
[2025-05-07 17:29:30,280 INFO  L201        PluginConnector]: Adding new model de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction CFG 07.05 05:29:30 BoogieIcfgContainer
[2025-05-07 17:29:30,280 INFO  L131        PluginConnector]: ------------------------ END TraceAbstraction----------------------------
[2025-05-07 17:29:30,282 INFO  L158              Benchmark]: Toolchain (without parser) took 7011.46ms. Allocated memory was 234.9MB in the beginning and 285.2MB in the end (delta: 50.3MB). Free memory was 150.3MB in the beginning and 161.1MB in the end (delta: -10.8MB). Peak memory consumption was 50.5MB. Max. memory is 33.6GB.
[2025-05-07 17:29:30,282 INFO  L158              Benchmark]: SmtParser took 0.30ms. Allocated memory is still 234.9MB. Free memory is still 172.2MB. There was no memory consumed. Max. memory is 33.6GB.
[2025-05-07 17:29:30,283 INFO  L158              Benchmark]: ChcToBoogie took 31.15ms. Allocated memory is still 234.9MB. Free memory was 150.3MB in the beginning and 147.7MB in the end (delta: 2.6MB). There was no memory consumed. Max. memory is 33.6GB.
[2025-05-07 17:29:30,284 INFO  L158              Benchmark]: Boogie Preprocessor took 68.83ms. Allocated memory is still 234.9MB. Free memory was 147.7MB in the beginning and 144.5MB in the end (delta: 3.2MB). There was no memory consumed. Max. memory is 33.6GB.
[2025-05-07 17:29:30,284 INFO  L158              Benchmark]: RCFGBuilder took 374.60ms. Allocated memory is still 234.9MB. Free memory was 144.5MB in the beginning and 185.2MB in the end (delta: -40.7MB). Peak memory consumption was 42.9MB. Max. memory is 33.6GB.
[2025-05-07 17:29:30,285 INFO  L158              Benchmark]: TraceAbstraction took 6533.22ms. Allocated memory was 234.9MB in the beginning and 285.2MB in the end (delta: 50.3MB). Free memory was 184.7MB in the beginning and 161.1MB in the end (delta: 23.6MB). Peak memory consumption was 74.7MB. Max. memory is 33.6GB.
[2025-05-07 17:29:30,288 INFO  L338   ainManager$Toolchain]: #######################  End [Toolchain 1] #######################
 --- Results ---
 * Results from de.uni_freiburg.informatik.ultimate.core:
  - StatisticsResult: Toolchain Benchmarks
    Benchmark results are:
 * SmtParser took 0.30ms. Allocated memory is still 234.9MB. Free memory is still 172.2MB. There was no memory consumed. Max. memory is 33.6GB.
 * ChcToBoogie took 31.15ms. Allocated memory is still 234.9MB. Free memory was 150.3MB in the beginning and 147.7MB in the end (delta: 2.6MB). There was no memory consumed. Max. memory is 33.6GB.
 * Boogie Preprocessor took 68.83ms. Allocated memory is still 234.9MB. Free memory was 147.7MB in the beginning and 144.5MB in the end (delta: 3.2MB). There was no memory consumed. Max. memory is 33.6GB.
 * RCFGBuilder took 374.60ms. Allocated memory is still 234.9MB. Free memory was 144.5MB in the beginning and 185.2MB in the end (delta: -40.7MB). Peak memory consumption was 42.9MB. Max. memory is 33.6GB.
 * TraceAbstraction took 6533.22ms. Allocated memory was 234.9MB in the beginning and 285.2MB in the end (delta: 50.3MB). Free memory was 184.7MB in the beginning and 161.1MB in the end (delta: 23.6MB). Peak memory consumption was 74.7MB. Max. memory is 33.6GB.
 * Results from de.uni_freiburg.informatik.ultimate.plugins.generator.traceabstraction:
  - StatisticsResult: ErrorAutomatonStatistics
    NumberErrorTraces: 0, NumberStatementsAllTraces: 0, NumberRelevantStatements: 0, 0.0s ErrorAutomatonConstructionTimeTotal, 0.0s FaulLocalizationTime, NumberStatementsFirstTrace: -1, TraceLengthAvg: 0, 0.0s ErrorAutomatonConstructionTimeAvg, 0.0s ErrorAutomatonDifferenceTimeAvg, 0.0s ErrorAutomatonDifferenceTimeTotal, NumberOfNoEnhancement: 0, NumberOfFiniteEnhancement: 0, NumberOfInfiniteEnhancement: 0
  - PositiveResult [Unknown line] : the set of constraint Horn clauses is satisfiable
    For all program executions holds that the set of constraint Horn clauses is satisfiable at this location
  - StatisticsResult: Ultimate Automizer benchmark data
    CFG has 26 procedures, 102 locations, 152 edges, 1 error locations. Started 1 CEGAR loops. OverallTime: 6.5s, OverallIterations: 8, TraceHistogramMax: 2, PathProgramHistogramMax: 1, EmptinessCheckTime: 0.0s, AutomataDifference: 0.9s, DeadEndRemovalTime: 0.0s, HoareAnnotationTime: 0.0s, InitialAbstractionConstructionTime: 0.0s, HoareTripleCheckerStatistics: 0 mSolverCounterUnknown, 35 SdHoareTripleChecker+Valid, 0.6s IncrementalHoareTripleChecker+Time, 0 mSdLazyCounter, 16 mSDsluCounter, 5902 SdHoareTripleChecker+Invalid, 0.5s Time, 0 mProtectedAction, 0 SdHoareTripleChecker+Unchecked, 0 IncrementalHoareTripleChecker+Unchecked, 5091 mSDsCounter, 7 IncrementalHoareTripleChecker+Valid, 0 mProtectedPredicate, 858 IncrementalHoareTripleChecker+Invalid, 865 SdHoareTripleChecker+Unknown, 0 mSolverCounterNotChecked, 7 mSolverCounterUnsat, 811 mSDtfsCounter, 858 mSolverCounterSat, 0.0s SdHoareTripleChecker+Time, 0 IncrementalHoareTripleChecker+Unknown, PredicateUnifierStatistics: 0 DeclaredPredicates, 420 GetRequests, 331 SyntacticMatches, 0 SemanticMatches, 89 ConstructedPredicates, 0 IntricatePredicates, 0 DeprecatedPredicates, 45 ImplicationChecksByTransitivity, 0.7s Time, 0.0s BasicInterpolantAutomatonTime, BiggestAbstraction: size=107occurred in iteration=5, InterpolantAutomatonStates: 71, traceCheckStatistics: No data available, InterpolantConsolidationStatistics: No data available, PathInvariantsStatistics: No data available, 0/0 InterpolantCoveringCapability, TotalInterpolationStatistics: No data available, 0.0s DumpTime, AutomataMinimizationStatistics: 0.1s AutomataMinimizationTime, 8 MinimizatonAttempts, 15 StatesRemovedByMinimization, 5 NontrivialMinimizations, HoareAnnotationStatistics: No data available, RefinementEngineStatistics: TRACE_CHECK: 0.2s SsaConstructionTime, 0.4s SatisfiabilityAnalysisTime, 3.8s InterpolantComputationTime, 924 NumberOfCodeBlocks, 924 NumberOfCodeBlocksAsserted, 10 NumberOfCheckSat, 1021 ConstructedInterpolants, 0 QuantifiedInterpolants, 1667 SizeOfPredicates, 5 NumberOfNonLiveVariables, 2873 ConjunctsInSsa, 69 ConjunctsInUnsatCore, 11 InterpolantComputations, 7 PerfectInterpolantSequences, 71/95 InterpolantCoveringCapability, INVARIANT_SYNTHESIS: No data available, INTERPOLANT_CONSOLIDATION: No data available, ABSTRACT_INTERPRETATION: No data available, PDR: No data available, ACCELERATED_INTERPOLATION: No data available, SIFA: No data available, ReuseStatistics: No data available, ConComCheckerStatistics: No data available
  - AllSpecificationsHoldResult: All specifications hold
    1 specifications checked. All of them hold
RESULT: Ultimate proved your program to be correct!
[2025-05-07 17:29:30,319 INFO  L552       MonitoredProcess]: [MP /usr/bin/z3 SMTLIB2_COMPLIANT=true -memory:2024 -smt2 -in -t:2000 (1)] Ended with exit code 0
Received shutdown request...
sat
