[     0.001] (INFO) [37mWrite simplified version of 'old' to output_1/old_Main_simplified.st[0m [[33mmodularization.kt:253[0m]
[     0.051] (INFO) [37mMaintain frames in old[0m [[33mmodularization.kt:256[0m]
[     3.724] (INFO) [37mWrite complete SMV file of 'old' to output_1/old_Main_simplified.smv[0m [[33mmodularization.kt:261[0m]
[     4.682] (INFO) [37mWrite simplified version of 'new' to output_1/new_Main_simplified.st[0m [[33mmodularization.kt:253[0m]
[     4.696] (INFO) [37mMaintain frames in new[0m [[33mmodularization.kt:256[0m]
[     8.052] (INFO) [37mWrite complete SMV file of 'new' to output_1/new_Main_simplified.smv[0m [[33mmodularization.kt:261[0m]
[     8.890] (INFO) [37mTop level relation: Sensor_CraneOnConveyor = (0sd16_16160 < Sensor_AnalogCranePosition & Sensor_AnalogCranePosition < 0sd16_16260) & Sensor_CraneOnMagazin = (0sd16_24290 < Sensor_AnalogCranePosition & Sensor_AnalogCranePosition < 0sd16_24390) & Sensor_CranePositionStamp = (0sd16_8160 < Sensor_AnalogCranePosition & Sensor_AnalogCranePosition < 0sd16_8260) & Sensor_CraneOnConveyor = Sensor_CraneOnConveyor & Sensor_CraneOnMagazin = Sensor_CraneOnMagazin & Sensor_CranePosition = Sensor_CranePosition & Sensor_CranePositionStamp = Sensor_CranePositionStamp & Sensor_CraneDown = Sensor_CraneDown & Sensor_CraneSucked = Sensor_CraneSucked & Sensor_CraneUp = Sensor_CraneUp & Sensor_MagazinCapacitiveSensor = Sensor_MagazinCapacitiveSensor & Sensor_MagazinEmergencyStop = Sensor_MagazinEmergencyStop & Sensor_MagazinOpticalSensor = Sensor_MagazinOpticalSensor & Sensor_MagazinSwitchManuellAutomatic = Sensor_MagazinSwitchManuellAutomatic & Sensor_SliderMovedOut = Sensor_SliderMovedOut & Sensor_SliderNotMovedOut = Sensor_SliderNotMovedOut & Sensor_SorterCapacitiveSensorPusher1 = Sensor_SorterCapacitiveSensorPusher1 & Sensor_SorterCapacitiveSensorPusher2 = Sensor_SorterCapacitiveSensorPusher2 & Sensor_SorterEmergencyStop = Sensor_SorterEmergencyStop & Sensor_SorterLightbarrierCraneInterface = Sensor_SorterLightbarrierCraneInterface & Sensor_SorterLightnessSensorPusher1 = Sensor_SorterLightnessSensorPusher1 & Sensor_SorterLightnessSensorPusher2 = Sensor_SorterLightnessSensorPusher2 & Sensor_SorterLightnessSensorPusher3 = Sensor_SorterLightnessSensorPusher3 & Sensor_SorterLightnesssensorCraneInterfaceInverse = Sensor_SorterLightnesssensorCraneInterfaceInverse & Sensor_SorterPusher1MovedIn = Sensor_SorterPusher1MovedIn & Sensor_SorterPusher1MovedOut = Sensor_SorterPusher1MovedOut & Sensor_SorterPusher2MovedIn = Sensor_SorterPusher2MovedIn & Sensor_SorterPusher2MovedOut = Sensor_SorterPusher2MovedOut & Sensor_SorterStartButton = Sensor_SorterStartButton & Sensor_SorterSwitchManuellAutomatic = Sensor_SorterSwitchManuellAutomatic & Sensor_StampEmergencyStop = Sensor_StampEmergencyStop & Sensor_StampLowered = Sensor_StampLowered & Sensor_StampSliderFilled = Sensor_StampSliderFilled & Sensor_StampSliderSensorMovedIn = Sensor_StampSliderSensorMovedIn & Sensor_StampSliderSensorMovedOut = Sensor_StampSliderSensorMovedOut & Sensor_StampStartButton = Sensor_StampStartButton & Sensor_StampSwitchManuellAutomatic = Sensor_StampSwitchManuellAutomatic & Sensor_StampUp = Sensor_StampUp & Sensor_StartButtonMagazin = Sensor_StartButtonMagazin & Sensor_WorkpieceReady = Sensor_WorkpieceReady[0m [[33mapp.kt:91[0m]
[     8.890] (INFO) [37mTop level conditon: TRUE[0m [[33mapp.kt:92[0m]
[     8.891] (INFO) [37mProof for perfect equality? true[0m [[33mapp.kt:93[0m]
[     8.891] (INFO) [37mOnly equalities? true[0m [[33mapp.kt:94[0m]
line 1:99 missing ')' at '<EOF>'
[     8.923] (INFO) [37mFollowing sub-calls are marked as proven by assumption: [proofEquivalence(Main.Stamp_abstracted,Main.Stamp_abstracted):][0m [[33mapp.kt:118[0m]
[     8.924] (INFO) [37mCall sites for the old program: Main[0m [[33mmodularization.kt:195[0m]
[     8.924] (INFO) [37mMain.Mag.0 in line @346:8, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.924] (INFO) [37mMain.Crane.0 in line @510:8, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.925] (INFO) [37mMain.Crane.TimeDelay_Timer.0 in line @646:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.925] (INFO) [37mMain.Crane.Timer1.0 in line @954:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.925] (INFO) [37mMain.Crane.Timer1.1 in line @1114:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.925] (INFO) [37mMain.Stamp.0 in line @1401:8, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.925] (INFO) [37mMain.Stamp.Timer.0 in line @1594:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.926] (INFO) [37mMain.Stamp.Timer.1 in line @1626:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.926] (INFO) [37mMain.Conveyor.0 in line @1662:8, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.926] (INFO) [37mMain.Conveyor.Timer_Conveyor_Test.0 in line @1723:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.927] (INFO) [37mMain.Conveyor.Timer_Conveyor_Runtime.0 in line @1738:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.927] (INFO) [37mMain.Conveyor.Timer_Conveyor_Test.1 in line @1784:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.927] (INFO) [37mMain.Conveyor.Timer_Conveyor_Test.2 in line @1814:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.927] (INFO) [37mMain.Conveyor.Timer_Conveyor_Runtime.1 in line @1875:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.928] (INFO) [37mMain.Conveyor.Timer_Conveyor_Runtime.2 in line @1905:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.928] (INFO) [37mMain.Pusher.0 in line @1948:8, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.928] (INFO) [37mMain.Pusher.Timer2.0 in line @2111:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.928] (INFO) [37mMain.Pusher.Timer3.0 in line @2140:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.929] (INFO) [37mMain.Pusher.Timer_Slide2_Black.0 in line @2179:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.929] (INFO) [37mMain.Pusher.Timer3.1 in line @2222:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.929] (INFO) [37mMain.Pusher.Timer3.2 in line @2265:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.929] (INFO) [37mMain.Pusher.Timer_Slide2_Black.1 in line @2281:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.929] (INFO) [37mMain.Pusher.Timer_Slide1_Black.0 in line @2320:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.930] (INFO) [37mMain.Pusher.Timer3.3 in line @2363:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.930] (INFO) [37mMain.Pusher.Timer3.4 in line @2406:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.930] (INFO) [37mMain.Pusher.Timer_Slide1_Black.1 in line @2422:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.930] (INFO) [37mMain.Pusher.Timer_Slide1_White.0 in line @2473:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.931] (INFO) [37mMain.Pusher.Timer3.5 in line @2515:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.931] (INFO) [37mMain.Pusher.Timer3.6 in line @2558:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.931] (INFO) [37mMain.Pusher.Timer_Slide1_White.1 in line @2574:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.931] (INFO) [37mMain.Pusher.Timer_Slide2_White.0 in line @2619:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.931] (INFO) [37mMain.Pusher.Timer3.7 in line @2661:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.931] (INFO) [37mMain.Pusher.Timer3.8 in line @2704:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.932] (INFO) [37mMain.Pusher.Timer_Slide2_White.1 in line @2720:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.932] (INFO) [37mMain.Pusher.Timer3.9 in line @2758:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.932] (INFO) [37mMain.Pusher.Timer3.10 in line @2786:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.932] (INFO) [37mMain.Pusher.Timer_Slide1_Metal.0 in line @2837:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.932] (INFO) [37mMain.Pusher.Timer3.11 in line @2879:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.933] (INFO) [37mMain.Pusher.Timer3.12 in line @2922:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.933] (INFO) [37mMain.Pusher.Timer_Slide1_Metal.1 in line @2938:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.933] (INFO) [37mMain.Pusher.Timer_Slide2_Metal.0 in line @2983:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.933] (INFO) [37mMain.Pusher.Timer3.13 in line @3025:28, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.933] (INFO) [37mMain.Pusher.Timer3.14 in line @3068:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.934] (INFO) [37mMain.Pusher.Timer_Slide2_Metal.1 in line @3084:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.934] (INFO) [37mMain.Pusher.Timer3.15 in line @3122:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.934] (INFO) [37mMain.Pusher.Timer3.16 in line @3150:24, embedded from null[0m [[33mmodularization.kt:197[0m]
[     8.934] (INFO) [37mCall sites for the new program: Main[0m [[33mmodularization.kt:199[0m]
[     8.934] (INFO) [37mMain.Mag.0 in line @348:8, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.934] (INFO) [37mMain.Crane.0 in line @513:8, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Crane.TimeDelay_Timer.0 in line @648:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Crane.Timer1.0 in line @956:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Crane.Timer1.1 in line @1116:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Stamp.0 in line @1404:8, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Stamp.Timer.0 in line @1598:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Stamp.Timer.1 in line @1630:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.935] (INFO) [37mMain.Conveyor.0 in line @1666:8, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Conveyor.Timer_Conveyor_Test.0 in line @1727:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Conveyor.Timer_Conveyor_Runtime.0 in line @1742:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Conveyor.Timer_Conveyor_Test.1 in line @1788:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Conveyor.Timer_Conveyor_Test.2 in line @1818:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Conveyor.Timer_Conveyor_Runtime.1 in line @1879:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Conveyor.Timer_Conveyor_Runtime.2 in line @1909:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Pusher.0 in line @1952:8, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.936] (INFO) [37mMain.Pusher.Timer2.0 in line @2115:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.937] (INFO) [37mMain.Pusher.Timer3.0 in line @2144:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.937] (INFO) [37mMain.Pusher.Timer_Slide2_Black.0 in line @2183:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.937] (INFO) [37mMain.Pusher.Timer3.1 in line @2226:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.937] (INFO) [37mMain.Pusher.Timer3.2 in line @2269:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.937] (INFO) [37mMain.Pusher.Timer_Slide2_Black.1 in line @2285:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.938] (INFO) [37mMain.Pusher.Timer_Slide1_Black.0 in line @2324:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.938] (INFO) [37mMain.Pusher.Timer3.3 in line @2367:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.938] (INFO) [37mMain.Pusher.Timer3.4 in line @2410:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.938] (INFO) [37mMain.Pusher.Timer_Slide1_Black.1 in line @2426:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.938] (INFO) [37mMain.Pusher.Timer_Slide1_White.0 in line @2477:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.938] (INFO) [37mMain.Pusher.Timer3.5 in line @2519:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer3.6 in line @2562:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer_Slide1_White.1 in line @2578:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer_Slide2_White.0 in line @2623:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer3.7 in line @2665:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer3.8 in line @2708:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer_Slide2_White.1 in line @2724:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.939] (INFO) [37mMain.Pusher.Timer3.9 in line @2762:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer3.10 in line @2790:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer_Slide1_Metal.0 in line @2841:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer3.11 in line @2883:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer3.12 in line @2926:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer_Slide1_Metal.1 in line @2942:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer_Slide2_Metal.0 in line @2987:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer3.13 in line @3029:28, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer3.14 in line @3072:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.940] (INFO) [37mMain.Pusher.Timer_Slide2_Metal.1 in line @3088:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.941] (INFO) [37mMain.Pusher.Timer3.15 in line @3126:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.941] (INFO) [37mMain.Pusher.Timer3.16 in line @3154:24, embedded from null[0m [[33mmodularization.kt:201[0m]
[     8.941] (INFO) [37mMatched Main.Pusher.Timer_Slide1_Black.0 to Main.Pusher.Timer_Slide1_Black.0[0m [[33mmodularization.kt:206[0m]
[     8.941] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.941] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.943] (INFO) [37m	Input-Relation: old.Pusher$Timer_Slide1_Black$IN = new.Pusher$Timer_Slide1_Black$IN & old.Pusher$Timer_Slide1_Black$PT = new.Pusher$Timer_Slide1_Black$PT[0m [[33mmodularization.kt:209[0m]
[     8.943] (INFO) [37m	Output-Relation: old.Pusher$Timer_Slide1_Black$ET = new.Pusher$Timer_Slide1_Black$ET & old.Pusher$Timer_Slide1_Black$Q = new.Pusher$Timer_Slide1_Black$Q[0m [[33mmodularization.kt:210[0m]
[     8.943] (INFO) [37mMatched Main.Pusher.Timer_Slide1_Metal.0 to Main.Pusher.Timer_Slide1_Metal.0[0m [[33mmodularization.kt:206[0m]
[     8.943] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.944] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.944] (INFO) [37m	Input-Relation: old.Pusher$Timer_Slide1_Metal$IN = new.Pusher$Timer_Slide1_Metal$IN & old.Pusher$Timer_Slide1_Metal$PT = new.Pusher$Timer_Slide1_Metal$PT[0m [[33mmodularization.kt:209[0m]
[     8.944] (INFO) [37m	Output-Relation: old.Pusher$Timer_Slide1_Metal$ET = new.Pusher$Timer_Slide1_Metal$ET & old.Pusher$Timer_Slide1_Metal$Q = new.Pusher$Timer_Slide1_Metal$Q[0m [[33mmodularization.kt:210[0m]
[     8.946] (INFO) [37mMatched Main.Crane.0 to Main.Crane.0[0m [[33mmodularization.kt:206[0m]
[     8.946] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.946] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.947] (INFO) [37m	Input-Relation: old.Crane$CraneDown = new.Crane$CraneDown & old.Crane$CraneSucked = new.Crane$CraneSucked & old.Crane$CraneUp = new.Crane$CraneUp & old.Crane$MagazinCapacitiveSensor = new.Crane$MagazinCapacitiveSensor & old.Crane$MagazinOpticalSensor = new.Crane$MagazinOpticalSensor & old.Crane$SFCReset = new.Crane$SFCReset & old.Crane$SliderMovedOut = new.Crane$SliderMovedOut & old.Crane$SliderNotMovedOut = new.Crane$SliderNotMovedOut & old.Crane$StampLowered = new.Crane$StampLowered & old.Crane$StampSliderFilled = new.Crane$StampSliderFilled & old.Crane$StampSliderSensorMovedOut = new.Crane$StampSliderSensorMovedOut & old.Crane$StartButtonMagazin = new.Crane$StartButtonMagazin & old.Crane$StartVar = new.Crane$StartVar & old.Crane$WorkpieceReady = new.Crane$WorkpieceReady & old.Crane$CraneOnConveyor = (0sd16_16160 < new.Crane$AnalogCranePosition & new.Crane$AnalogCranePosition < 0sd16_16260) & old.Crane$CraneOnMagazin = (0sd16_24290 < new.Crane$AnalogCranePosition & new.Crane$AnalogCranePosition < 0sd16_24390) & old.Crane$CranePositionStamp = (0sd16_8160 < new.Crane$AnalogCranePosition & new.Crane$AnalogCranePosition < 0sd16_8260)[0m [[33mmodularization.kt:209[0m]
[     8.947] (INFO) [37m	Output-Relation: old.Crane$AnalogCranePressure = new.Crane$AnalogCranePressure & old.Crane$CraneLower = new.Crane$CraneLower & old.Crane$CraneTurnClockwise = new.Crane$CraneTurnClockwise & old.Crane$CraneTurnCounterclockwise = new.Crane$CraneTurnCounterclockwise & old.Crane$MagazinVacuumOff = new.Crane$MagazinVacuumOff & old.Crane$MagazinVacuumOn = new.Crane$MagazinVacuumOn & old.Crane$StartCommandCrane = new.Crane$StartCommandCrane[0m [[33mmodularization.kt:210[0m]
[     8.947] (INFO) [37mMatched Main.Crane.Timer1.0 to Main.Crane.Timer1.0[0m [[33mmodularization.kt:206[0m]
[     8.947] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.948] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.948] (INFO) [37m	Input-Relation: old.Crane$Timer1$IN = new.Crane$Timer1$IN & old.Crane$Timer1$PT = new.Crane$Timer1$PT[0m [[33mmodularization.kt:209[0m]
[     8.948] (INFO) [37m	Output-Relation: old.Crane$Timer1$ET = new.Crane$Timer1$ET & old.Crane$Timer1$Q = new.Crane$Timer1$Q[0m [[33mmodularization.kt:210[0m]
[     8.948] (INFO) [37mMatched Main.Conveyor.Timer_Conveyor_Runtime.0 to Main.Conveyor.Timer_Conveyor_Runtime.0[0m [[33mmodularization.kt:206[0m]
[     8.948] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.948] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.949] (INFO) [37m	Input-Relation: old.Conveyor$Timer_Conveyor_Runtime$IN = new.Conveyor$Timer_Conveyor_Runtime$IN & old.Conveyor$Timer_Conveyor_Runtime$PT = new.Conveyor$Timer_Conveyor_Runtime$PT[0m [[33mmodularization.kt:209[0m]
[     8.949] (INFO) [37m	Output-Relation: old.Conveyor$Timer_Conveyor_Runtime$ET = new.Conveyor$Timer_Conveyor_Runtime$ET & old.Conveyor$Timer_Conveyor_Runtime$Q = new.Conveyor$Timer_Conveyor_Runtime$Q[0m [[33mmodularization.kt:210[0m]
[     8.949] (INFO) [37mMatched Main.Crane.TimeDelay_Timer.0 to Main.Crane.TimeDelay_Timer.0[0m [[33mmodularization.kt:206[0m]
[     8.950] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.950] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.950] (INFO) [37m	Input-Relation: old.Crane$TimeDelay_Timer$IN = new.Crane$TimeDelay_Timer$IN & old.Crane$TimeDelay_Timer$PT = new.Crane$TimeDelay_Timer$PT[0m [[33mmodularization.kt:209[0m]
[     8.950] (INFO) [37m	Output-Relation: old.Crane$TimeDelay_Timer$ET = new.Crane$TimeDelay_Timer$ET & old.Crane$TimeDelay_Timer$Q = new.Crane$TimeDelay_Timer$Q[0m [[33mmodularization.kt:210[0m]
[     8.951] (INFO) [37mMatched Main.Conveyor.0 to Main.Conveyor.0[0m [[33mmodularization.kt:206[0m]
[     8.951] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.951] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.952] (INFO) [37m	Input-Relation: old.Conveyor$SFCReset = new.Conveyor$SFCReset & old.Conveyor$SorterLightbarrierCraneInterface = new.Conveyor$SorterLightbarrierCraneInterface & old.Conveyor$SorterStartButton = new.Conveyor$SorterStartButton & old.Conveyor$SorterSwitchManuellAutomatic = new.Conveyor$SorterSwitchManuellAutomatic & old.Conveyor$StartVar = new.Conveyor$StartVar[0m [[33mmodularization.kt:209[0m]
[     8.952] (INFO) [37m	Output-Relation: old.Conveyor$SorterConveyorTowardsCrane = new.Conveyor$SorterConveyorTowardsCrane & old.Conveyor$SorterConveyorTowardsStacker = new.Conveyor$SorterConveyorTowardsStacker & old.Conveyor$SorterGreenLampConveyor = new.Conveyor$SorterGreenLampConveyor & old.Conveyor$SorterWhiteLampConveyor = new.Conveyor$SorterWhiteLampConveyor & old.Conveyor$StartCommandConveyor = new.Conveyor$StartCommandConveyor[0m [[33mmodularization.kt:210[0m]
[     8.952] (INFO) [37mMatched Main.Pusher.Timer_Slide1_White.0 to Main.Pusher.Timer_Slide1_White.0[0m [[33mmodularization.kt:206[0m]
[     8.952] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.952] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.952] (INFO) [37m	Input-Relation: old.Pusher$Timer_Slide1_White$IN = new.Pusher$Timer_Slide1_White$IN & old.Pusher$Timer_Slide1_White$PT = new.Pusher$Timer_Slide1_White$PT[0m [[33mmodularization.kt:209[0m]
[     8.953] (INFO) [37m	Output-Relation: old.Pusher$Timer_Slide1_White$ET = new.Pusher$Timer_Slide1_White$ET & old.Pusher$Timer_Slide1_White$Q = new.Pusher$Timer_Slide1_White$Q[0m [[33mmodularization.kt:210[0m]
[     8.953] (INFO) [37mMatched Main.Pusher.Timer_Slide2_Black.0 to Main.Pusher.Timer_Slide2_Black.0[0m [[33mmodularization.kt:206[0m]
[     8.953] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.953] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.953] (INFO) [37m	Input-Relation: old.Pusher$Timer_Slide2_Black$IN = new.Pusher$Timer_Slide2_Black$IN & old.Pusher$Timer_Slide2_Black$PT = new.Pusher$Timer_Slide2_Black$PT[0m [[33mmodularization.kt:209[0m]
[     8.953] (INFO) [37m	Output-Relation: old.Pusher$Timer_Slide2_Black$ET = new.Pusher$Timer_Slide2_Black$ET & old.Pusher$Timer_Slide2_Black$Q = new.Pusher$Timer_Slide2_Black$Q[0m [[33mmodularization.kt:210[0m]
[     8.954] (INFO) [37mMatched Main.Pusher.Timer_Slide2_Metal.0 to Main.Pusher.Timer_Slide2_Metal.0[0m [[33mmodularization.kt:206[0m]
[     8.954] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.954] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.954] (INFO) [37m	Input-Relation: old.Pusher$Timer_Slide2_Metal$IN = new.Pusher$Timer_Slide2_Metal$IN & old.Pusher$Timer_Slide2_Metal$PT = new.Pusher$Timer_Slide2_Metal$PT[0m [[33mmodularization.kt:209[0m]
[     8.954] (INFO) [37m	Output-Relation: old.Pusher$Timer_Slide2_Metal$ET = new.Pusher$Timer_Slide2_Metal$ET & old.Pusher$Timer_Slide2_Metal$Q = new.Pusher$Timer_Slide2_Metal$Q[0m [[33mmodularization.kt:210[0m]
[     8.955] (INFO) [37mMatched Main.Mag.0 to Main.Mag.0[0m [[33mmodularization.kt:206[0m]
[     8.955] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.955] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.956] (INFO) [37m	Input-Relation: old.Mag$CraneDown = new.Mag$CraneDown & old.Mag$CraneOnConveyor = new.Mag$CraneOnConveyor & old.Mag$CraneOnMagazin = new.Mag$CraneOnMagazin & old.Mag$CraneSucked = new.Mag$CraneSucked & old.Mag$CraneUp = new.Mag$CraneUp & old.Mag$SFCReset = new.Mag$SFCReset & old.Mag$SliderMovedOut = new.Mag$SliderMovedOut & old.Mag$SliderNotMovedOut = new.Mag$SliderNotMovedOut & old.Mag$StartButtonMagazin = new.Mag$StartButtonMagazin & old.Mag$StartVar = new.Mag$StartVar & old.Mag$WorkpieceReady = new.Mag$WorkpieceReady & old.Mag$CraneOnConveyor = (0sd16_16160 < new.Mag$AnalogCranePosition & new.Mag$AnalogCranePosition < 0sd16_16260) & old.Mag$CraneOnMagazin = (0sd16_24290 < new.Mag$AnalogCranePosition & new.Mag$AnalogCranePosition < 0sd16_24390)[0m [[33mmodularization.kt:209[0m]
[     8.956] (INFO) [37m	Output-Relation: old.Mag$CraneLower = new.Mag$CraneLower & old.Mag$CraneTurnClockwise = new.Mag$CraneTurnClockwise & old.Mag$CraneTurnCounterclockwise = new.Mag$CraneTurnCounterclockwise & old.Mag$MagazinGreenLamp = new.Mag$MagazinGreenLamp & old.Mag$MagazinSlider = new.Mag$MagazinSlider & old.Mag$MagazinVacuumOff = new.Mag$MagazinVacuumOff & old.Mag$MagazinVacuumOn = new.Mag$MagazinVacuumOn & old.Mag$MagazinWhiteLamp = new.Mag$MagazinWhiteLamp & old.Mag$StartCommandMagazin = new.Mag$StartCommandMagazin[0m [[33mmodularization.kt:210[0m]
[     8.956] (INFO) [37mMatched Main.Conveyor.Timer_Conveyor_Test.0 to Main.Conveyor.Timer_Conveyor_Test.0[0m [[33mmodularization.kt:206[0m]
[     8.956] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.956] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.956] (INFO) [37m	Input-Relation: old.Conveyor$Timer_Conveyor_Test$IN = new.Conveyor$Timer_Conveyor_Test$IN & old.Conveyor$Timer_Conveyor_Test$PT = new.Conveyor$Timer_Conveyor_Test$PT[0m [[33mmodularization.kt:209[0m]
[     8.957] (INFO) [37m	Output-Relation: old.Conveyor$Timer_Conveyor_Test$ET = new.Conveyor$Timer_Conveyor_Test$ET & old.Conveyor$Timer_Conveyor_Test$Q = new.Conveyor$Timer_Conveyor_Test$Q[0m [[33mmodularization.kt:210[0m]
[     8.957] (INFO) [37mMatched Main.Pusher.Timer2.0 to Main.Pusher.Timer2.0[0m [[33mmodularization.kt:206[0m]
[     8.957] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.957] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.957] (INFO) [37m	Input-Relation: old.Pusher$Timer2$IN = new.Pusher$Timer2$IN & old.Pusher$Timer2$PT = new.Pusher$Timer2$PT[0m [[33mmodularization.kt:209[0m]
[     8.957] (INFO) [37m	Output-Relation: old.Pusher$Timer2$ET = new.Pusher$Timer2$ET & old.Pusher$Timer2$Q = new.Pusher$Timer2$Q[0m [[33mmodularization.kt:210[0m]
[     8.958] (INFO) [37mMatched Main.Pusher.Timer3.0 to Main.Pusher.Timer3.0[0m [[33mmodularization.kt:206[0m]
[     8.958] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.958] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.958] (INFO) [37m	Input-Relation: old.Pusher$Timer3$IN = new.Pusher$Timer3$IN & old.Pusher$Timer3$PT = new.Pusher$Timer3$PT[0m [[33mmodularization.kt:209[0m]
[     8.958] (INFO) [37m	Output-Relation: old.Pusher$Timer3$ET = new.Pusher$Timer3$ET & old.Pusher$Timer3$Q = new.Pusher$Timer3$Q[0m [[33mmodularization.kt:210[0m]
[     8.960] (INFO) [37mMatched Main.Pusher.0 to Main.Pusher.0[0m [[33mmodularization.kt:206[0m]
[     8.960] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.960] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.960] (INFO) [37m	Input-Relation: old.Pusher$SFCReset = new.Pusher$SFCReset & old.Pusher$SorterCapacitiveSensorPusher1 = new.Pusher$SorterCapacitiveSensorPusher1 & old.Pusher$SorterCapacitiveSensorPusher2 = new.Pusher$SorterCapacitiveSensorPusher2 & old.Pusher$SorterLightbarrierCraneInterface = new.Pusher$SorterLightbarrierCraneInterface & old.Pusher$SorterLightnessSensorPusher1 = new.Pusher$SorterLightnessSensorPusher1 & old.Pusher$SorterLightnessSensorPusher2 = new.Pusher$SorterLightnessSensorPusher2 & old.Pusher$SorterLightnessSensorPusher3 = new.Pusher$SorterLightnessSensorPusher3 & old.Pusher$SorterLightnesssensorCraneInterfaceInverse = new.Pusher$SorterLightnesssensorCraneInterfaceInverse & old.Pusher$SorterPusher1MovedIn = new.Pusher$SorterPusher1MovedIn & old.Pusher$SorterPusher1MovedOut = new.Pusher$SorterPusher1MovedOut & old.Pusher$SorterPusher2MovedIn = new.Pusher$SorterPusher2MovedIn & old.Pusher$SorterPusher2MovedOut = new.Pusher$SorterPusher2MovedOut & old.Pusher$SorterStartButton = new.Pusher$SorterStartButton & old.Pusher$StartVar = new.Pusher$StartVar[0m [[33mmodularization.kt:209[0m]
[     8.960] (INFO) [37m	Output-Relation: old.Pusher$PusherY1 = new.Pusher$PusherY1 & old.Pusher$PusherY2 = new.Pusher$PusherY2[0m [[33mmodularization.kt:210[0m]
[     8.961] (INFO) [37mMatched Main.Stamp.Timer.0 to Main.Stamp.Timer.0[0m [[33mmodularization.kt:206[0m]
[     8.961] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.961] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.961] (INFO) [37m	Input-Relation: old.Stamp$Timer$IN = new.Stamp$Timer$IN & old.Stamp$Timer$PT = new.Stamp$Timer$PT[0m [[33mmodularization.kt:209[0m]
[     8.961] (INFO) [37m	Output-Relation: old.Stamp$Timer$ET = new.Stamp$Timer$ET & old.Stamp$Timer$Q = new.Stamp$Timer$Q[0m [[33mmodularization.kt:210[0m]
[     8.961] (INFO) [37mMatched Main.Pusher.Timer_Slide2_White.0 to Main.Pusher.Timer_Slide2_White.0[0m [[33mmodularization.kt:206[0m]
[     8.962] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.962] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.962] (INFO) [37m	Input-Relation: old.Pusher$Timer_Slide2_White$IN = new.Pusher$Timer_Slide2_White$IN & old.Pusher$Timer_Slide2_White$PT = new.Pusher$Timer_Slide2_White$PT[0m [[33mmodularization.kt:209[0m]
[     8.962] (INFO) [37m	Output-Relation: old.Pusher$Timer_Slide2_White$ET = new.Pusher$Timer_Slide2_White$ET & old.Pusher$Timer_Slide2_White$Q = new.Pusher$Timer_Slide2_White$Q[0m [[33mmodularization.kt:210[0m]
[     8.962] (INFO) [37mMatched Main.Stamp.0 to Main.Stamp.0[0m [[33mmodularization.kt:206[0m]
[     8.963] (INFO) [37m	Is perfect?: true[0m [[33mmodularization.kt:207[0m]
[     8.963] (INFO) [37m	Context: TRUE[0m [[33mmodularization.kt:208[0m]
[     8.963] (INFO) [37m	Input-Relation: old.Stamp$CraneDown = new.Stamp$CraneDown & old.Stamp$CraneOnConveyor = new.Stamp$CraneOnConveyor & old.Stamp$CraneOnConveyor = (0sd16_16160 < new.Stamp$AnalogCranePosition & new.Stamp$AnalogCranePosition < 0sd16_16260) & old.Stamp$CranePositionStamp = (0sd16_8160 < new.Stamp$AnalogCranePosition & new.Stamp$AnalogCranePosition < 0sd16_8260) & old.Stamp$CranePositionStamp = new.Stamp$CranePositionStamp & old.Stamp$CraneSucked = new.Stamp$CraneSucked & old.Stamp$CraneUp = new.Stamp$CraneUp & old.Stamp$SFCReset = new.Stamp$SFCReset & old.Stamp$StampLowered = new.Stamp$StampLowered & old.Stamp$StampSliderFilled = new.Stamp$StampSliderFilled & old.Stamp$StampSliderSensorMovedIn = new.Stamp$StampSliderSensorMovedIn & old.Stamp$StampSliderSensorMovedOut = new.Stamp$StampSliderSensorMovedOut & old.Stamp$StampStartButton = new.Stamp$StampStartButton & old.Stamp$StampUp = new.Stamp$StampUp & old.Stamp$StartVar = new.Stamp$StartVar[0m [[33mmodularization.kt:209[0m]
[     8.964] (INFO) [37m	Output-Relation: old.Stamp$StampGreenLamp = new.Stamp$StampGreenLamp & old.Stamp$StampPressure = new.Stamp$StampPressure & old.Stamp$StampPusher = new.Stamp$StampPusher & old.Stamp$StampSliderMovedIn = new.Stamp$StampSliderMovedIn & old.Stamp$StampSliderMovedOut = new.Stamp$StampSliderMovedOut & old.Stamp$StampWhiteLamp = new.Stamp$StampWhiteLamp & old.Stamp$StartCommandStamp = new.Stamp$StartCommandStamp[0m [[33mmodularization.kt:210[0m]
[     8.964] (INFO) [37mOutput folder: output_1[0m [[33mapp.kt:123[0m]
[     8.964] (INFO) [37mStart with the proof[0m [[33mapp.kt:124[0m]
[     8.993] (INFO) [37mproofEquivalence(Main,Main):  Main.0: (LoC:2403) (Vars: [180] (39) => (40))[0m [[33mstrategy.kt:73[0m]
[     9.005] (INFO) [37mproofEquivalence(Main,Main):  Main.0: (LoC:2405) (Vars: [177] (40) => (44))[0m [[33mstrategy.kt:74[0m]
[     9.006] (INFO) [37mcheckCache(Main,Main):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[     9.006] (INFO) [37mproofBodyEquivalenceSource(Main,Main):  Frame Main is *not* syntactical equivalent to  Main[0m [[33mstrategy.kt:349[0m]
[     9.007] (INFO) [37mproofBodyEquivalenceSSA(Main,Main):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[     9.008] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Proof equivalence of frames Main and Main[0m [[33mstrategy.kt:385[0m]
[     9.008] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Translate Main to SMT[0m [[33mstrategy.kt:387[0m]
[     9.321] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Translate Main to SMT[0m [[33mstrategy.kt:392[0m]
[     9.506] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Create assertion[0m [[33mstrategy.kt:395[0m]
[     9.507] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Write SMT problem into output_1/Main_Main_equiv.smt[0m [[33mstrategy.kt:424[0m]
[     9.508] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Run z3 for Main_Main_equiv.smt[0m [[33mstrategy.kt:430[0m]
[     9.565] (INFO) [37mproofBodyEquivalenceSMT(Main,Main):  Result of z3 is false for Main_Main_equiv.smt[0m [[33mstrategy.kt:433[0m]
[     9.603] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main,Main):  Add new regression verification contract[0m [[33mstrategy.kt:283[0m]
[     9.603] (INFO) [37mFrame Main_abstracted.0 to Main_abstracted.0[0m [[33mstrategy.kt:284[0m]
[     9.603] (INFO) [37m	Context: TRUE[0m [[33mstrategy.kt:285[0m]
[     9.604] (INFO) [37m	Input-Relation: old.Sensor_CraneOnConveyor = (0sd16_16160 < new.Sensor_AnalogCranePosition & new.Sensor_AnalogCranePosition < 0sd16_16260) & old.Sensor_CraneOnMagazin = (0sd16_24290 < new.Sensor_AnalogCranePosition & new.Sensor_AnalogCranePosition < 0sd16_24390) & old.Sensor_CranePositionStamp = (0sd16_8160 < new.Sensor_AnalogCranePosition & new.Sensor_AnalogCranePosition < 0sd16_8260) & old.Sensor_CraneOnConveyor = new.Sensor_CraneOnConveyor & old.Sensor_CraneOnMagazin = new.Sensor_CraneOnMagazin & old.Sensor_CranePosition = new.Sensor_CranePosition & old.Sensor_CranePositionStamp = new.Sensor_CranePositionStamp & old.Sensor_CraneDown = new.Sensor_CraneDown & old.Sensor_CraneSucked = new.Sensor_CraneSucked & old.Sensor_CraneUp = new.Sensor_CraneUp & old.Sensor_MagazinCapacitiveSensor = new.Sensor_MagazinCapacitiveSensor & old.Sensor_MagazinEmergencyStop = new.Sensor_MagazinEmergencyStop & old.Sensor_MagazinOpticalSensor = new.Sensor_MagazinOpticalSensor & old.Sensor_MagazinSwitchManuellAutomatic = new.Sensor_MagazinSwitchManuellAutomatic & old.Sensor_SliderMovedOut = new.Sensor_SliderMovedOut & old.Sensor_SliderNotMovedOut = new.Sensor_SliderNotMovedOut & old.Sensor_SorterCapacitiveSensorPusher1 = new.Sensor_SorterCapacitiveSensorPusher1 & old.Sensor_SorterCapacitiveSensorPusher2 = new.Sensor_SorterCapacitiveSensorPusher2 & old.Sensor_SorterEmergencyStop = new.Sensor_SorterEmergencyStop & old.Sensor_SorterLightbarrierCraneInterface = new.Sensor_SorterLightbarrierCraneInterface & old.Sensor_SorterLightnessSensorPusher1 = new.Sensor_SorterLightnessSensorPusher1 & old.Sensor_SorterLightnessSensorPusher2 = new.Sensor_SorterLightnessSensorPusher2 & old.Sensor_SorterLightnessSensorPusher3 = new.Sensor_SorterLightnessSensorPusher3 & old.Sensor_SorterLightnesssensorCraneInterfaceInverse = new.Sensor_SorterLightnesssensorCraneInterfaceInverse & old.Sensor_SorterPusher1MovedIn = new.Sensor_SorterPusher1MovedIn & old.Sensor_SorterPusher1MovedOut = new.Sensor_SorterPusher1MovedOut & old.Sensor_SorterPusher2MovedIn = new.Sensor_SorterPusher2MovedIn & old.Sensor_SorterPusher2MovedOut = new.Sensor_SorterPusher2MovedOut & old.Sensor_SorterStartButton = new.Sensor_SorterStartButton & old.Sensor_SorterSwitchManuellAutomatic = new.Sensor_SorterSwitchManuellAutomatic & old.Sensor_StampEmergencyStop = new.Sensor_StampEmergencyStop & old.Sensor_StampLowered = new.Sensor_StampLowered & old.Sensor_StampSliderFilled = new.Sensor_StampSliderFilled & old.Sensor_StampSliderSensorMovedIn = new.Sensor_StampSliderSensorMovedIn & old.Sensor_StampSliderSensorMovedOut = new.Sensor_StampSliderSensorMovedOut & old.Sensor_StampStartButton = new.Sensor_StampStartButton & old.Sensor_StampSwitchManuellAutomatic = new.Sensor_StampSwitchManuellAutomatic & old.Sensor_StampUp = new.Sensor_StampUp & old.Sensor_StartButtonMagazin = new.Sensor_StartButtonMagazin & old.Sensor_WorkpieceReady = new.Sensor_WorkpieceReady & old.Pusher$Timer_Slide1_Black$ET = new.Pusher$Timer_Slide1_Black$ET & old.Pusher$Timer_Slide1_Black$Q = new.Pusher$Timer_Slide1_Black$Q & old.Pusher$Timer_Slide1_Metal$ET = new.Pusher$Timer_Slide1_Metal$ET & old.Pusher$Timer_Slide1_Metal$Q = new.Pusher$Timer_Slide1_Metal$Q & old.Main$Crane$0$Crane$AnalogCranePressure__random = new.Main$Crane$0$Crane$AnalogCranePressure__random & old.Main$Crane$0$Crane$CraneLower__random = new.Main$Crane$0$Crane$CraneLower__random & old.Main$Crane$0$Crane$CraneTurnClockwise__random = new.Main$Crane$0$Crane$CraneTurnClockwise__random & old.Main$Crane$0$Crane$CraneTurnCounterclockwise__random = new.Main$Crane$0$Crane$CraneTurnCounterclockwise__random & old.Main$Crane$0$Crane$MagazinVacuumOff__random = new.Main$Crane$0$Crane$MagazinVacuumOff__random & old.Main$Crane$0$Crane$MagazinVacuumOn__random = new.Main$Crane$0$Crane$MagazinVacuumOn__random & old.Main$Crane$0$Crane$StartCommandCrane__random = new.Main$Crane$0$Crane$StartCommandCrane__random & old.Crane$Timer1$ET = new.Crane$Timer1$ET & old.Crane$Timer1$Q = new.Crane$Timer1$Q & old.Conveyor$Timer_Conveyor_Runtime$ET = new.Conveyor$Timer_Conveyor_Runtime$ET & old.Conveyor$Timer_Conveyor_Runtime$Q = new.Conveyor$Timer_Conveyor_Runtime$Q & old.Crane$TimeDelay_Timer$ET = new.Crane$TimeDelay_Timer$ET & old.Crane$TimeDelay_Timer$Q = new.Crane$TimeDelay_Timer$Q & old.Main$Conveyor$0$Conveyor$SorterConveyorTowardsCrane__random = new.Main$Conveyor$0$Conveyor$SorterConveyorTowardsCrane__random & old.Main$Conveyor$0$Conveyor$SorterConveyorTowardsStacker__random = new.Main$Conveyor$0$Conveyor$SorterConveyorTowardsStacker__random & old.Main$Conveyor$0$Conveyor$SorterGreenLampConveyor__random = new.Main$Conveyor$0$Conveyor$SorterGreenLampConveyor__random & old.Main$Conveyor$0$Conveyor$SorterWhiteLampConveyor__random = new.Main$Conveyor$0$Conveyor$SorterWhiteLampConveyor__random & old.Main$Conveyor$0$Conveyor$StartCommandConveyor__random = new.Main$Conveyor$0$Conveyor$StartCommandConveyor__random & old.Pusher$Timer_Slide1_White$ET = new.Pusher$Timer_Slide1_White$ET & old.Pusher$Timer_Slide1_White$Q = new.Pusher$Timer_Slide1_White$Q & old.Pusher$Timer_Slide2_Black$ET = new.Pusher$Timer_Slide2_Black$ET & old.Pusher$Timer_Slide2_Black$Q = new.Pusher$Timer_Slide2_Black$Q & old.Pusher$Timer_Slide2_Metal$ET = new.Pusher$Timer_Slide2_Metal$ET & old.Pusher$Timer_Slide2_Metal$Q = new.Pusher$Timer_Slide2_Metal$Q & old.Main$Mag$0$Mag$CraneLower__random = new.Main$Mag$0$Mag$CraneLower__random & old.Main$Mag$0$Mag$CraneTurnClockwise__random = new.Main$Mag$0$Mag$CraneTurnClockwise__random & old.Main$Mag$0$Mag$CraneTurnCounterclockwise__random = new.Main$Mag$0$Mag$CraneTurnCounterclockwise__random & old.Main$Mag$0$Mag$MagazinGreenLamp__random = new.Main$Mag$0$Mag$MagazinGreenLamp__random & old.Main$Mag$0$Mag$MagazinSlider__random = new.Main$Mag$0$Mag$MagazinSlider__random & old.Main$Mag$0$Mag$MagazinVacuumOff__random = new.Main$Mag$0$Mag$MagazinVacuumOff__random & old.Main$Mag$0$Mag$MagazinVacuumOn__random = new.Main$Mag$0$Mag$MagazinVacuumOn__random & old.Main$Mag$0$Mag$MagazinWhiteLamp__random = new.Main$Mag$0$Mag$MagazinWhiteLamp__random & old.Main$Mag$0$Mag$StartCommandMagazin__random = new.Main$Mag$0$Mag$StartCommandMagazin__random & old.Conveyor$Timer_Conveyor_Test$ET = new.Conveyor$Timer_Conveyor_Test$ET & old.Conveyor$Timer_Conveyor_Test$Q = new.Conveyor$Timer_Conveyor_Test$Q & old.Pusher$Timer2$ET = new.Pusher$Timer2$ET & old.Pusher$Timer2$Q = new.Pusher$Timer2$Q & old.Pusher$Timer3$ET = new.Pusher$Timer3$ET & old.Pusher$Timer3$Q = new.Pusher$Timer3$Q & old.Main$Pusher$0$Pusher$PusherY1__random = new.Main$Pusher$0$Pusher$PusherY1__random & old.Main$Pusher$0$Pusher$PusherY2__random = new.Main$Pusher$0$Pusher$PusherY2__random & old.Stamp$Timer$ET = new.Stamp$Timer$ET & old.Stamp$Timer$Q = new.Stamp$Timer$Q & old.Pusher$Timer_Slide2_White$ET = new.Pusher$Timer_Slide2_White$ET & old.Pusher$Timer_Slide2_White$Q = new.Pusher$Timer_Slide2_White$Q & old.Main$Stamp$0$Stamp$StampGreenLamp__random = new.Main$Stamp$0$Stamp$StampGreenLamp__random & old.Main$Stamp$0$Stamp$StampPressure__random = new.Main$Stamp$0$Stamp$StampPressure__random & old.Main$Stamp$0$Stamp$StampPusher__random = new.Main$Stamp$0$Stamp$StampPusher__random & old.Main$Stamp$0$Stamp$StampSliderMovedIn__random = new.Main$Stamp$0$Stamp$StampSliderMovedIn__random & old.Main$Stamp$0$Stamp$StampSliderMovedOut__random = new.Main$Stamp$0$Stamp$StampSliderMovedOut__random & old.Main$Stamp$0$Stamp$StampWhiteLamp__random = new.Main$Stamp$0$Stamp$StampWhiteLamp__random & old.Main$Stamp$0$Stamp$StartCommandStamp__random = new.Main$Stamp$0$Stamp$StartCommandStamp__random[0m [[33mstrategy.kt:286[0m]
[     9.606] (INFO) [37m	Output-Relation: old.Actuator_CraneLower = new.Actuator_CraneLower & old.Actuator_CraneTurnClockwise = new.Actuator_CraneTurnClockwise & old.Actuator_CraneTurnCounterclockwise = new.Actuator_CraneTurnCounterclockwise & old.Actuator_MagazinGreenLamp = new.Actuator_MagazinGreenLamp & old.Actuator_MagazinSlider = new.Actuator_MagazinSlider & old.Actuator_MagazinVacuumOff = new.Actuator_MagazinVacuumOff & old.Actuator_MagazinVacuumOn = new.Actuator_MagazinVacuumOn & old.Actuator_MagazinWhiteLamp = new.Actuator_MagazinWhiteLamp & old.Actuator_PusherY1 = new.Actuator_PusherY1 & old.Actuator_PusherY2 = new.Actuator_PusherY2 & old.Actuator_SorterConveyorTowardsCrane = new.Actuator_SorterConveyorTowardsCrane & old.Actuator_SorterConveyorTowardsStacker = new.Actuator_SorterConveyorTowardsStacker & old.Actuator_SorterGreenLampConveyor = new.Actuator_SorterGreenLampConveyor & old.Actuator_SorterWhiteLampConveyor = new.Actuator_SorterWhiteLampConveyor & old.Actuator_StampGreenLamp = new.Actuator_StampGreenLamp & old.Actuator_StampPusher = new.Actuator_StampPusher & old.Actuator_StampSliderMovedIn = new.Actuator_StampSliderMovedIn & old.Actuator_StampSliderMovedOut = new.Actuator_StampSliderMovedOut & old.Actuator_StampWhiteLamp = new.Actuator_StampWhiteLamp & old.Main$Pusher$Timer_Slide1_Black_ccnt = new.Main$Pusher$Timer_Slide1_Black_ccnt & old.Pusher$Timer_Slide1_Black$IN = new.Pusher$Timer_Slide1_Black$IN & old.Pusher$Timer_Slide1_Black$PT = new.Pusher$Timer_Slide1_Black$PT & old.Main$Pusher$Timer_Slide1_Metal_ccnt = new.Main$Pusher$Timer_Slide1_Metal_ccnt & old.Pusher$Timer_Slide1_Metal$IN = new.Pusher$Timer_Slide1_Metal$IN & old.Pusher$Timer_Slide1_Metal$PT = new.Pusher$Timer_Slide1_Metal$PT & old.Main$Crane_ccnt = new.Main$Crane_ccnt & old.Main$Crane$0$Crane$CraneDown__input = new.Main$Crane$0$Crane$CraneDown__input & old.Main$Crane$0$Crane$CraneSucked__input = new.Main$Crane$0$Crane$CraneSucked__input & old.Main$Crane$0$Crane$CraneUp__input = new.Main$Crane$0$Crane$CraneUp__input & old.Main$Crane$0$Crane$MagazinCapacitiveSensor__input = new.Main$Crane$0$Crane$MagazinCapacitiveSensor__input & old.Main$Crane$0$Crane$MagazinOpticalSensor__input = new.Main$Crane$0$Crane$MagazinOpticalSensor__input & old.Main$Crane$0$Crane$SFCReset__input = new.Main$Crane$0$Crane$SFCReset__input & old.Main$Crane$0$Crane$SliderMovedOut__input = new.Main$Crane$0$Crane$SliderMovedOut__input & old.Main$Crane$0$Crane$SliderNotMovedOut__input = new.Main$Crane$0$Crane$SliderNotMovedOut__input & old.Main$Crane$0$Crane$StampLowered__input = new.Main$Crane$0$Crane$StampLowered__input & old.Main$Crane$0$Crane$StampSliderFilled__input = new.Main$Crane$0$Crane$StampSliderFilled__input & old.Main$Crane$0$Crane$StampSliderSensorMovedOut__input = new.Main$Crane$0$Crane$StampSliderSensorMovedOut__input & old.Main$Crane$0$Crane$StartButtonMagazin__input = new.Main$Crane$0$Crane$StartButtonMagazin__input & old.Main$Crane$0$Crane$StartVar__input = new.Main$Crane$0$Crane$StartVar__input & old.Main$Crane$0$Crane$WorkpieceReady__input = new.Main$Crane$0$Crane$WorkpieceReady__input & old.Main$Crane$0$Crane$CraneOnConveyor__input = (0sd16_16160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_16260) & old.Main$Crane$0$Crane$CraneOnMagazin__input = (0sd16_24290 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_24390) & old.Main$Crane$0$Crane$CranePositionStamp__input = (0sd16_8160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_8260) & old.Main$Crane$Timer1_ccnt = new.Main$Crane$Timer1_ccnt & old.Crane$Timer1$IN = new.Crane$Timer1$IN & old.Crane$Timer1$PT = new.Crane$Timer1$PT & old.Main$Conveyor$Timer_Conveyor_Runtime_ccnt = new.Main$Conveyor$Timer_Conveyor_Runtime_ccnt & old.Conveyor$Timer_Conveyor_Runtime$IN = new.Conveyor$Timer_Conveyor_Runtime$IN & old.Conveyor$Timer_Conveyor_Runtime$PT = new.Conveyor$Timer_Conveyor_Runtime$PT & old.Main$Crane$TimeDelay_Timer_ccnt = new.Main$Crane$TimeDelay_Timer_ccnt & old.Crane$TimeDelay_Timer$IN = new.Crane$TimeDelay_Timer$IN & old.Crane$TimeDelay_Timer$PT = new.Crane$TimeDelay_Timer$PT & old.Main$Conveyor_ccnt = new.Main$Conveyor_ccnt & old.Main$Conveyor$0$Conveyor$SFCReset__input = new.Main$Conveyor$0$Conveyor$SFCReset__input & old.Main$Conveyor$0$Conveyor$SorterLightbarrierCraneInterface__input = new.Main$Conveyor$0$Conveyor$SorterLightbarrierCraneInterface__input & old.Main$Conveyor$0$Conveyor$SorterStartButton__input = new.Main$Conveyor$0$Conveyor$SorterStartButton__input & old.Main$Conveyor$0$Conveyor$SorterSwitchManuellAutomatic__input = new.Main$Conveyor$0$Conveyor$SorterSwitchManuellAutomatic__input & old.Main$Conveyor$0$Conveyor$StartVar__input = new.Main$Conveyor$0$Conveyor$StartVar__input & old.Main$Pusher$Timer_Slide1_White_ccnt = new.Main$Pusher$Timer_Slide1_White_ccnt & old.Pusher$Timer_Slide1_White$IN = new.Pusher$Timer_Slide1_White$IN & old.Pusher$Timer_Slide1_White$PT = new.Pusher$Timer_Slide1_White$PT & old.Main$Pusher$Timer_Slide2_Black_ccnt = new.Main$Pusher$Timer_Slide2_Black_ccnt & old.Pusher$Timer_Slide2_Black$IN = new.Pusher$Timer_Slide2_Black$IN & old.Pusher$Timer_Slide2_Black$PT = new.Pusher$Timer_Slide2_Black$PT & old.Main$Pusher$Timer_Slide2_Metal_ccnt = new.Main$Pusher$Timer_Slide2_Metal_ccnt & old.Pusher$Timer_Slide2_Metal$IN = new.Pusher$Timer_Slide2_Metal$IN & old.Pusher$Timer_Slide2_Metal$PT = new.Pusher$Timer_Slide2_Metal$PT & old.Main$Mag_ccnt = new.Main$Mag_ccnt & old.Main$Mag$0$Mag$CraneDown__input = new.Main$Mag$0$Mag$CraneDown__input & old.Main$Mag$0$Mag$CraneOnConveyor__input = new.Main$Mag$0$Mag$CraneOnConveyor__input & old.Main$Mag$0$Mag$CraneOnMagazin__input = new.Main$Mag$0$Mag$CraneOnMagazin__input & old.Main$Mag$0$Mag$CraneSucked__input = new.Main$Mag$0$Mag$CraneSucked__input & old.Main$Mag$0$Mag$CraneUp__input = new.Main$Mag$0$Mag$CraneUp__input & old.Main$Mag$0$Mag$SFCReset__input = new.Main$Mag$0$Mag$SFCReset__input & old.Main$Mag$0$Mag$SliderMovedOut__input = new.Main$Mag$0$Mag$SliderMovedOut__input & old.Main$Mag$0$Mag$SliderNotMovedOut__input = new.Main$Mag$0$Mag$SliderNotMovedOut__input & old.Main$Mag$0$Mag$StartButtonMagazin__input = new.Main$Mag$0$Mag$StartButtonMagazin__input & old.Main$Mag$0$Mag$StartVar__input = new.Main$Mag$0$Mag$StartVar__input & old.Main$Mag$0$Mag$WorkpieceReady__input = new.Main$Mag$0$Mag$WorkpieceReady__input & old.Main$Mag$0$Mag$CraneOnConveyor__input = (0sd16_16160 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_16260) & old.Main$Mag$0$Mag$CraneOnMagazin__input = (0sd16_24290 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_24390) & old.Main$Conveyor$Timer_Conveyor_Test_ccnt = new.Main$Conveyor$Timer_Conveyor_Test_ccnt & old.Conveyor$Timer_Conveyor_Test$IN = new.Conveyor$Timer_Conveyor_Test$IN & old.Conveyor$Timer_Conveyor_Test$PT = new.Conveyor$Timer_Conveyor_Test$PT & old.Main$Pusher$Timer2_ccnt = new.Main$Pusher$Timer2_ccnt & old.Pusher$Timer2$IN = new.Pusher$Timer2$IN & old.Pusher$Timer2$PT = new.Pusher$Timer2$PT & old.Main$Pusher$Timer3_ccnt = new.Main$Pusher$Timer3_ccnt & old.Pusher$Timer3$IN = new.Pusher$Timer3$IN & old.Pusher$Timer3$PT = new.Pusher$Timer3$PT & old.Main$Pusher_ccnt = new.Main$Pusher_ccnt & old.Main$Pusher$0$Pusher$SFCReset__input = new.Main$Pusher$0$Pusher$SFCReset__input & old.Main$Pusher$0$Pusher$SorterCapacitiveSensorPusher1__input = new.Main$Pusher$0$Pusher$SorterCapacitiveSensorPusher1__input & old.Main$Pusher$0$Pusher$SorterCapacitiveSensorPusher2__input = new.Main$Pusher$0$Pusher$SorterCapacitiveSensorPusher2__input & old.Main$Pusher$0$Pusher$SorterLightbarrierCraneInterface__input = new.Main$Pusher$0$Pusher$SorterLightbarrierCraneInterface__input & old.Main$Pusher$0$Pusher$SorterLightnessSensorPusher1__input = new.Main$Pusher$0$Pusher$SorterLightnessSensorPusher1__input & old.Main$Pusher$0$Pusher$SorterLightnessSensorPusher2__input = new.Main$Pusher$0$Pusher$SorterLightnessSensorPusher2__input & old.Main$Pusher$0$Pusher$SorterLightnessSensorPusher3__input = new.Main$Pusher$0$Pusher$SorterLightnessSensorPusher3__input & old.Main$Pusher$0$Pusher$SorterLightnesssensorCraneInterfaceInverse__input = new.Main$Pusher$0$Pusher$SorterLightnesssensorCraneInterfaceInverse__input & old.Main$Pusher$0$Pusher$SorterPusher1MovedIn__input = new.Main$Pusher$0$Pusher$SorterPusher1MovedIn__input & old.Main$Pusher$0$Pusher$SorterPusher1MovedOut__input = new.Main$Pusher$0$Pusher$SorterPusher1MovedOut__input & old.Main$Pusher$0$Pusher$SorterPusher2MovedIn__input = new.Main$Pusher$0$Pusher$SorterPusher2MovedIn__input & old.Main$Pusher$0$Pusher$SorterPusher2MovedOut__input = new.Main$Pusher$0$Pusher$SorterPusher2MovedOut__input & old.Main$Pusher$0$Pusher$SorterStartButton__input = new.Main$Pusher$0$Pusher$SorterStartButton__input & old.Main$Pusher$0$Pusher$StartVar__input = new.Main$Pusher$0$Pusher$StartVar__input & old.Main$Stamp$Timer_ccnt = new.Main$Stamp$Timer_ccnt & old.Stamp$Timer$IN = new.Stamp$Timer$IN & old.Stamp$Timer$PT = new.Stamp$Timer$PT & old.Main$Pusher$Timer_Slide2_White_ccnt = new.Main$Pusher$Timer_Slide2_White_ccnt & old.Pusher$Timer_Slide2_White$IN = new.Pusher$Timer_Slide2_White$IN & old.Pusher$Timer_Slide2_White$PT = new.Pusher$Timer_Slide2_White$PT & old.Main$Stamp_ccnt = new.Main$Stamp_ccnt & old.Main$Stamp$0$Stamp$CraneDown__input = new.Main$Stamp$0$Stamp$CraneDown__input & old.Main$Stamp$0$Stamp$CraneOnConveyor__input = new.Main$Stamp$0$Stamp$CraneOnConveyor__input & old.Main$Stamp$0$Stamp$CraneOnConveyor__input = (0sd16_16160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_16260) & old.Main$Stamp$0$Stamp$CranePositionStamp__input = (0sd16_8160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_8260) & old.Main$Stamp$0$Stamp$CranePositionStamp__input = new.Main$Stamp$0$Stamp$CranePositionStamp__input & old.Main$Stamp$0$Stamp$CraneSucked__input = new.Main$Stamp$0$Stamp$CraneSucked__input & old.Main$Stamp$0$Stamp$CraneUp__input = new.Main$Stamp$0$Stamp$CraneUp__input & old.Main$Stamp$0$Stamp$SFCReset__input = new.Main$Stamp$0$Stamp$SFCReset__input & old.Main$Stamp$0$Stamp$StampLowered__input = new.Main$Stamp$0$Stamp$StampLowered__input & old.Main$Stamp$0$Stamp$StampSliderFilled__input = new.Main$Stamp$0$Stamp$StampSliderFilled__input & old.Main$Stamp$0$Stamp$StampSliderSensorMovedIn__input = new.Main$Stamp$0$Stamp$StampSliderSensorMovedIn__input & old.Main$Stamp$0$Stamp$StampSliderSensorMovedOut__input = new.Main$Stamp$0$Stamp$StampSliderSensorMovedOut__input & old.Main$Stamp$0$Stamp$StampStartButton__input = new.Main$Stamp$0$Stamp$StampStartButton__input & old.Main$Stamp$0$Stamp$StampUp__input = new.Main$Stamp$0$Stamp$StampUp__input & old.Main$Stamp$0$Stamp$StartVar__input = new.Main$Stamp$0$Stamp$StartVar__input[0m [[33mstrategy.kt:287[0m]
[     9.606] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main,Main):  Recursion for proofing abstracted body[0m [[33mstrategy.kt:290[0m]
[     9.609] (INFO) [37mproofEquivalence(Main_abstracted,Main_abstracted):  Main_abstracted.0: (LoC:226) (Vars: [180] (39) => (40))[0m [[33mstrategy.kt:73[0m]
[     9.611] (INFO) [37mproofEquivalence(Main_abstracted,Main_abstracted):  Main_abstracted.0: (LoC:227) (Vars: [177] (40) => (44))[0m [[33mstrategy.kt:74[0m]
[     9.612] (INFO) [37mcheckCache(Main_abstracted,Main_abstracted):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[     9.612] (INFO) [37mproofBodyEquivalenceSource(Main_abstracted,Main_abstracted):  Frame Main_abstracted is *not* syntactical equivalent to  Main_abstracted[0m [[33mstrategy.kt:349[0m]
[     9.612] (INFO) [37mproofBodyEquivalenceSSA(Main_abstracted,Main_abstracted):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[     9.613] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Proof equivalence of frames Main_abstracted and Main_abstracted[0m [[33mstrategy.kt:385[0m]
[     9.613] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Translate Main_abstracted to SMT[0m [[33mstrategy.kt:387[0m]
[     9.634] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Translate Main_abstracted to SMT[0m [[33mstrategy.kt:392[0m]
[     9.652] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Create assertion[0m [[33mstrategy.kt:395[0m]
[     9.666] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Write SMT problem into output_1/Main_abstracted_Main_abstracted_equiv.smt[0m [[33mstrategy.kt:424[0m]
[     9.667] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Run z3 for Main_abstracted_Main_abstracted_equiv.smt[0m [[33mstrategy.kt:430[0m]
[     9.691] (INFO) [37mproofBodyEquivalenceSMT(Main_abstracted,Main_abstracted):  Result of z3 is false for Main_abstracted_Main_abstracted_equiv.smt[0m [[33mstrategy.kt:433[0m]
[     9.692] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main_abstracted,Main_abstracted):  declined, recursion protection triggered[0m [[33mstrategy.kt:203[0m]
[     9.738] (INFO) [37mproofBodyEquivalenceClassic(Main_abstracted,Main_abstracted):  Starting proof: output_1/Main_abstracted_Main_abstracted.smv, output_1/Main_abstracted_Main_abstracted.log[0m [[33mstrategy.kt:171[0m]
[     9.762] (INFO) [37mrunNuXmv: Run solver on output_1/Main_abstracted_Main_abstracted.smv[0m [[33mstrategy.kt:496[0m]
[     9.764] (INFO) [37mCreate nuXmv command file `output_1/ic3.xmv'[0m [[33mstrategy.kt:515[0m]
[     9.765] (INFO) [37m/home/weigl/share/nuXmv-1.1.1-Linux/bin/nuXmv -int /home/weigl/work/verifaps-lib/aps-rvt/examples/ppumod/12_13/output_1/Main_abstracted_Main_abstracted.smv[0m [[33mstrategy.kt:829[0m]
[    16.476] (INFO) [37mrunNuXmv: Solver finished for output_1/Main_abstracted_Main_abstracted.smv with edu.kit.iti.formal.smv.NuXMVOutput$Verified@5bf8fa12[0m [[33mstrategy.kt:500[0m]
[    16.476] (INFO) [37mproofBodyEquivalenceClassic(Main_abstracted,Main_abstracted):  Equality proven[0m [[33mstrategy.kt:176[0m]
[    16.476] (INFO) [37mproofEquivalence(Main_abstracted,Main_abstracted):  Timings: {cache=0, cmc=6784, mod=0, smt=79, src=0, ssa=1}[0m [[33mstrategy.kt:104[0m]
[    16.477] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main,Main):  Proving sub-frames: Timer_Slide1_Black=Timer_Slide1_Black, Timer_Slide1_Metal=Timer_Slide1_Metal, Crane=Crane, Timer1=Timer1, Timer_Conveyor_Runtime=Timer_Conveyor_Runtime, TimeDelay_Timer=TimeDelay_Timer, Conveyor=Conveyor, Timer_Slide1_White=Timer_Slide1_White, Timer_Slide2_Black=Timer_Slide2_Black, Timer_Slide2_Metal=Timer_Slide2_Metal, Mag=Mag, Timer_Conveyor_Test=Timer_Conveyor_Test, Timer2=Timer2, Timer3=Timer3, Pusher=Pusher, Timer=Timer, Timer_Slide2_White=Timer_Slide2_White, Stamp=Stamp[0m [[33mstrategy.kt:308[0m]
[    16.477] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_Black,Main.Pusher.Timer_Slide1_Black):  Main.Pusher.Timer_Slide1_Black.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    16.477] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_Black,Main.Pusher.Timer_Slide1_Black):  Main.Pusher.Timer_Slide1_Black.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    16.477] (INFO) [37mcheckCache(Main.Pusher.Timer_Slide1_Black,Main.Pusher.Timer_Slide1_Black):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    16.478] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer_Slide1_Black,Main.Pusher.Timer_Slide1_Black):  Frame Main.Pusher.Timer_Slide1_Black is syntactical equivalent to  Main.Pusher.Timer_Slide1_Black [0m [[33mstrategy.kt:347[0m]
[    16.478] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_Black,Main.Pusher.Timer_Slide1_Black):  Timings: {cache=0, src=1}[0m [[33mstrategy.kt:104[0m]
[    16.478] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_Metal,Main.Pusher.Timer_Slide1_Metal):  Main.Pusher.Timer_Slide1_Metal.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    16.479] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_Metal,Main.Pusher.Timer_Slide1_Metal):  Main.Pusher.Timer_Slide1_Metal.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    16.479] (INFO) [37mcheckCache(Main.Pusher.Timer_Slide1_Metal,Main.Pusher.Timer_Slide1_Metal):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    16.479] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer_Slide1_Metal,Main.Pusher.Timer_Slide1_Metal):  Frame Main.Pusher.Timer_Slide1_Metal is syntactical equivalent to  Main.Pusher.Timer_Slide1_Metal [0m [[33mstrategy.kt:347[0m]
[    16.479] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_Metal,Main.Pusher.Timer_Slide1_Metal):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    16.482] (INFO) [37mproofEquivalence(Main.Crane,Main.Crane):  Main.Crane.0: (LoC:663) (Vars: [15] (17) => (7))[0m [[33mstrategy.kt:73[0m]
[    16.484] (INFO) [37mproofEquivalence(Main.Crane,Main.Crane):  Main.Crane.0: (LoC:663) (Vars: [15] (15) => (8))[0m [[33mstrategy.kt:74[0m]
[    16.484] (INFO) [37mcheckCache(Main.Crane,Main.Crane):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    16.484] (INFO) [37mproofBodyEquivalenceSource(Main.Crane,Main.Crane):  Frame Main.Crane is *not* syntactical equivalent to  Main.Crane[0m [[33mstrategy.kt:349[0m]
[    16.484] (INFO) [37mproofBodyEquivalenceSSA(Main.Crane,Main.Crane):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[    16.484] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Proof equivalence of frames Main.Crane and Main.Crane[0m [[33mstrategy.kt:385[0m]
[    16.485] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Translate Main.Crane to SMT[0m [[33mstrategy.kt:387[0m]
[    16.585] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Translate Main.Crane to SMT[0m [[33mstrategy.kt:392[0m]
[    16.676] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Create assertion[0m [[33mstrategy.kt:395[0m]
[    16.676] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Write SMT problem into output_1/Main.Crane_Main.Crane_equiv.smt[0m [[33mstrategy.kt:424[0m]
[    16.677] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Run z3 for Main.Crane_Main.Crane_equiv.smt[0m [[33mstrategy.kt:430[0m]
[    16.700] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane,Main.Crane):  Result of z3 is false for Main.Crane_Main.Crane_equiv.smt[0m [[33mstrategy.kt:433[0m]
[    16.715] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main.Crane,Main.Crane):  Add new regression verification contract[0m [[33mstrategy.kt:283[0m]
[    16.715] (INFO) [37mFrame Main.Crane_abstracted.0 to Main.Crane_abstracted.0[0m [[33mstrategy.kt:284[0m]
[    16.715] (INFO) [37m	Context: TRUE[0m [[33mstrategy.kt:285[0m]
[    16.716] (INFO) [37m	Input-Relation: old.Crane$CraneDown = new.Crane$CraneDown & old.Crane$CraneSucked = new.Crane$CraneSucked & old.Crane$CraneUp = new.Crane$CraneUp & old.Crane$MagazinCapacitiveSensor = new.Crane$MagazinCapacitiveSensor & old.Crane$MagazinOpticalSensor = new.Crane$MagazinOpticalSensor & old.Crane$SFCReset = new.Crane$SFCReset & old.Crane$SliderMovedOut = new.Crane$SliderMovedOut & old.Crane$SliderNotMovedOut = new.Crane$SliderNotMovedOut & old.Crane$StampLowered = new.Crane$StampLowered & old.Crane$StampSliderFilled = new.Crane$StampSliderFilled & old.Crane$StampSliderSensorMovedOut = new.Crane$StampSliderSensorMovedOut & old.Crane$StartButtonMagazin = new.Crane$StartButtonMagazin & old.Crane$StartVar = new.Crane$StartVar & old.Crane$WorkpieceReady = new.Crane$WorkpieceReady & old.Crane$CraneOnConveyor = (0sd16_16160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_16260) & old.Crane$CraneOnMagazin = (0sd16_24290 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_24390) & old.Crane$CranePositionStamp = (0sd16_8160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_8260) & old.Main$Crane_ccnt = new.Main$Crane_ccnt & old.Crane$CraneDown = new.Crane$CraneDown & old.Crane$CraneSucked = new.Crane$CraneSucked & old.Crane$CraneUp = new.Crane$CraneUp & old.Crane$MagazinCapacitiveSensor = new.Crane$MagazinCapacitiveSensor & old.Crane$MagazinOpticalSensor = new.Crane$MagazinOpticalSensor & old.Crane$SFCReset = new.Crane$SFCReset & old.Crane$SliderMovedOut = new.Crane$SliderMovedOut & old.Crane$SliderNotMovedOut = new.Crane$SliderNotMovedOut & old.Crane$StampLowered = new.Crane$StampLowered & old.Crane$StampSliderFilled = new.Crane$StampSliderFilled & old.Crane$StampSliderSensorMovedOut = new.Crane$StampSliderSensorMovedOut & old.Crane$StartButtonMagazin = new.Crane$StartButtonMagazin & old.Crane$StartVar = new.Crane$StartVar & old.Crane$WorkpieceReady = new.Crane$WorkpieceReady & old.Crane$CraneOnConveyor = (0sd16_16160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_16260) & old.Crane$CraneOnMagazin = (0sd16_24290 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_24390) & old.Crane$CranePositionStamp = (0sd16_8160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_8260) & old.Main$Crane$Timer1$1$Crane$Timer1$ET__random = new.Main$Crane$Timer1$1$Crane$Timer1$ET__random & old.Main$Crane$Timer1$1$Crane$Timer1$Q__random = new.Main$Crane$Timer1$1$Crane$Timer1$Q__random & old.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$ET__random = new.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$ET__random & old.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$Q__random = new.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$Q__random[0m [[33mstrategy.kt:286[0m]
[    16.716] (INFO) [37m	Output-Relation: old.Main$Crane_ccnt = new.Main$Crane_ccnt & old.Crane$CraneDown = new.Crane$CraneDown & old.Crane$CraneSucked = new.Crane$CraneSucked & old.Crane$CraneUp = new.Crane$CraneUp & old.Crane$MagazinCapacitiveSensor = new.Crane$MagazinCapacitiveSensor & old.Crane$MagazinOpticalSensor = new.Crane$MagazinOpticalSensor & old.Crane$SFCReset = new.Crane$SFCReset & old.Crane$SliderMovedOut = new.Crane$SliderMovedOut & old.Crane$SliderNotMovedOut = new.Crane$SliderNotMovedOut & old.Crane$StampLowered = new.Crane$StampLowered & old.Crane$StampSliderFilled = new.Crane$StampSliderFilled & old.Crane$StampSliderSensorMovedOut = new.Crane$StampSliderSensorMovedOut & old.Crane$StartButtonMagazin = new.Crane$StartButtonMagazin & old.Crane$StartVar = new.Crane$StartVar & old.Crane$WorkpieceReady = new.Crane$WorkpieceReady & old.Crane$CraneOnConveyor = (0sd16_16160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_16260) & old.Crane$CraneOnMagazin = (0sd16_24290 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_24390) & old.Crane$CranePositionStamp = (0sd16_8160 < new.Main$Crane$0$Crane$AnalogCranePosition__input & new.Main$Crane$0$Crane$AnalogCranePosition__input < 0sd16_8260) & old.Main$Crane$Timer1_ccnt = new.Main$Crane$Timer1_ccnt & old.Main$Crane$Timer1$1$Crane$Timer1$IN__input = new.Main$Crane$Timer1$1$Crane$Timer1$IN__input & old.Main$Crane$Timer1$1$Crane$Timer1$PT__input = new.Main$Crane$Timer1$1$Crane$Timer1$PT__input & old.Main$Crane$TimeDelay_Timer_ccnt = new.Main$Crane$TimeDelay_Timer_ccnt & old.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$IN__input = new.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$IN__input & old.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$PT__input = new.Main$Crane$TimeDelay_Timer$0$Crane$TimeDelay_Timer$PT__input[0m [[33mstrategy.kt:287[0m]
[    16.716] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main.Crane,Main.Crane):  Recursion for proofing abstracted body[0m [[33mstrategy.kt:290[0m]
[    16.718] (INFO) [37mproofEquivalence(Main.Crane_abstracted,Main.Crane_abstracted):  Main.Crane_abstracted.0: (LoC:642) (Vars: [15] (17) => (7))[0m [[33mstrategy.kt:73[0m]
[    16.720] (INFO) [37mproofEquivalence(Main.Crane_abstracted,Main.Crane_abstracted):  Main.Crane_abstracted.0: (LoC:642) (Vars: [15] (15) => (8))[0m [[33mstrategy.kt:74[0m]
[    16.720] (INFO) [37mcheckCache(Main.Crane_abstracted,Main.Crane_abstracted):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    16.720] (INFO) [37mproofBodyEquivalenceSource(Main.Crane_abstracted,Main.Crane_abstracted):  Frame Main.Crane_abstracted is *not* syntactical equivalent to  Main.Crane_abstracted[0m [[33mstrategy.kt:349[0m]
[    16.720] (INFO) [37mproofBodyEquivalenceSSA(Main.Crane_abstracted,Main.Crane_abstracted):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[    16.721] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Proof equivalence of frames Main.Crane_abstracted and Main.Crane_abstracted[0m [[33mstrategy.kt:385[0m]
[    16.721] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Translate Main.Crane_abstracted to SMT[0m [[33mstrategy.kt:387[0m]
[    16.822] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Translate Main.Crane_abstracted to SMT[0m [[33mstrategy.kt:392[0m]
[    16.914] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Create assertion[0m [[33mstrategy.kt:395[0m]
[    16.915] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Write SMT problem into output_1/Main.Crane_abstracted_Main.Crane_abstracted_equiv.smt[0m [[33mstrategy.kt:424[0m]
[    16.915] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Run z3 for Main.Crane_abstracted_Main.Crane_abstracted_equiv.smt[0m [[33mstrategy.kt:430[0m]
[    16.959] (INFO) [37mproofBodyEquivalenceSMT(Main.Crane_abstracted,Main.Crane_abstracted):  Result of z3 is false for Main.Crane_abstracted_Main.Crane_abstracted_equiv.smt[0m [[33mstrategy.kt:433[0m]
[    16.959] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main.Crane_abstracted,Main.Crane_abstracted):  declined, recursion protection triggered[0m [[33mstrategy.kt:203[0m]
[    17.107] (INFO) [37mproofBodyEquivalenceClassic(Main.Crane_abstracted,Main.Crane_abstracted):  Starting proof: output_1/Main.Crane_abstracted_Main.Crane_abstracted.smv, output_1/Main.Crane_abstracted_Main.Crane_abstracted.log[0m [[33mstrategy.kt:171[0m]
[    17.179] (INFO) [37mrunNuXmv: Run solver on output_1/Main.Crane_abstracted_Main.Crane_abstracted.smv[0m [[33mstrategy.kt:496[0m]
[    17.180] (INFO) [37m/home/weigl/share/nuXmv-1.1.1-Linux/bin/nuXmv -int /home/weigl/work/verifaps-lib/aps-rvt/examples/ppumod/12_13/output_1/Main.Crane_abstracted_Main.Crane_abstracted.smv[0m [[33mstrategy.kt:829[0m]
[    31.418] (INFO) [37mrunNuXmv: Solver finished for output_1/Main.Crane_abstracted_Main.Crane_abstracted.smv with edu.kit.iti.formal.smv.NuXMVOutput$Verified@5bf8fa12[0m [[33mstrategy.kt:500[0m]
[    31.419] (INFO) [37mproofBodyEquivalenceClassic(Main.Crane_abstracted,Main.Crane_abstracted):  Equality proven[0m [[33mstrategy.kt:176[0m]
[    31.419] (INFO) [37mproofEquivalence(Main.Crane_abstracted,Main.Crane_abstracted):  Timings: {cache=0, cmc=14460, mod=0, smt=239, src=0, ssa=0}[0m [[33mstrategy.kt:104[0m]
[    31.419] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main.Crane,Main.Crane):  Proving sub-frames: Timer1=Timer1, TimeDelay_Timer=TimeDelay_Timer[0m [[33mstrategy.kt:308[0m]
[    31.419] (INFO) [37mproofEquivalence(Main.Crane.Timer1,Main.Crane.Timer1):  Main.Crane.Timer1.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.419] (INFO) [37mproofEquivalence(Main.Crane.Timer1,Main.Crane.Timer1):  Main.Crane.Timer1.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.419] (INFO) [37mcheckCache(Main.Crane.Timer1,Main.Crane.Timer1):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.420] (INFO) [37mproofBodyEquivalenceSource(Main.Crane.Timer1,Main.Crane.Timer1):  Frame Main.Crane.Timer1 is syntactical equivalent to  Main.Crane.Timer1 [0m [[33mstrategy.kt:347[0m]
[    31.420] (INFO) [37mproofEquivalence(Main.Crane.Timer1,Main.Crane.Timer1):  Timings: {cache=0, src=1}[0m [[33mstrategy.kt:104[0m]
[    31.420] (INFO) [37mproofEquivalence(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Main.Crane.TimeDelay_Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.421] (INFO) [37mproofEquivalence(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Main.Crane.TimeDelay_Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.421] (INFO) [37mcheckCache(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.421] (INFO) [37mproofBodyEquivalenceSource(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Frame Main.Crane.TimeDelay_Timer is syntactical equivalent to  Main.Crane.TimeDelay_Timer [0m [[33mstrategy.kt:347[0m]
[    31.421] (INFO) [37mproofEquivalence(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    31.421] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main.Crane,Main.Crane):  proved equality of subframes[0m [[33mstrategy.kt:326[0m]
[    31.421] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main.Crane,Main.Crane):  Equality proven[0m [[33mstrategy.kt:229[0m]
[    31.422] (INFO) [37mproofEquivalence(Main.Crane,Main.Crane):  Timings: {cache=0, mod=14720, smt=217, src=0, ssa=0}[0m [[33mstrategy.kt:104[0m]
[    31.422] (INFO) [37mproofEquivalence(Main.Crane.Timer1,Main.Crane.Timer1):  Main.Crane.Timer1.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.422] (INFO) [37mproofEquivalence(Main.Crane.Timer1,Main.Crane.Timer1):  Main.Crane.Timer1.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.422] (INFO) [37mcheckCache(Main.Crane.Timer1,Main.Crane.Timer1):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.422] (INFO) [37mproofBodyEquivalenceSource(Main.Crane.Timer1,Main.Crane.Timer1):  Frame Main.Crane.Timer1 is syntactical equivalent to  Main.Crane.Timer1 [0m [[33mstrategy.kt:347[0m]
[    31.423] (INFO) [37mproofEquivalence(Main.Crane.Timer1,Main.Crane.Timer1):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    31.423] (INFO) [37mproofEquivalence(Main.Conveyor.Timer_Conveyor_Runtime,Main.Conveyor.Timer_Conveyor_Runtime):  Main.Conveyor.Timer_Conveyor_Runtime.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.423] (INFO) [37mproofEquivalence(Main.Conveyor.Timer_Conveyor_Runtime,Main.Conveyor.Timer_Conveyor_Runtime):  Main.Conveyor.Timer_Conveyor_Runtime.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.423] (INFO) [37mcheckCache(Main.Conveyor.Timer_Conveyor_Runtime,Main.Conveyor.Timer_Conveyor_Runtime):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.423] (INFO) [37mproofBodyEquivalenceSource(Main.Conveyor.Timer_Conveyor_Runtime,Main.Conveyor.Timer_Conveyor_Runtime):  Frame Main.Conveyor.Timer_Conveyor_Runtime is syntactical equivalent to  Main.Conveyor.Timer_Conveyor_Runtime [0m [[33mstrategy.kt:347[0m]
[    31.424] (INFO) [37mproofEquivalence(Main.Conveyor.Timer_Conveyor_Runtime,Main.Conveyor.Timer_Conveyor_Runtime):  Timings: {cache=0, src=1}[0m [[33mstrategy.kt:104[0m]
[    31.424] (INFO) [37mproofEquivalence(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Main.Crane.TimeDelay_Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.424] (INFO) [37mproofEquivalence(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Main.Crane.TimeDelay_Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.424] (INFO) [37mcheckCache(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.424] (INFO) [37mproofBodyEquivalenceSource(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Frame Main.Crane.TimeDelay_Timer is syntactical equivalent to  Main.Crane.TimeDelay_Timer [0m [[33mstrategy.kt:347[0m]
[    31.424] (INFO) [37mproofEquivalence(Main.Crane.TimeDelay_Timer,Main.Crane.TimeDelay_Timer):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    31.425] (INFO) [37mproofEquivalence(Main.Conveyor,Main.Conveyor):  Main.Conveyor.0: (LoC:234) (Vars: [15] (5) => (5))[0m [[33mstrategy.kt:73[0m]
[    31.426] (INFO) [37mproofEquivalence(Main.Conveyor,Main.Conveyor):  Main.Conveyor.0: (LoC:234) (Vars: [15] (5) => (5))[0m [[33mstrategy.kt:74[0m]
[    31.427] (INFO) [37mcheckCache(Main.Conveyor,Main.Conveyor):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.427] (INFO) [37mproofBodyEquivalenceSource(Main.Conveyor,Main.Conveyor):  Frame Main.Conveyor is syntactical equivalent to  Main.Conveyor [0m [[33mstrategy.kt:347[0m]
[    31.428] (INFO) [37mproofEquivalence(Main.Conveyor,Main.Conveyor):  Timings: {cache=0, src=1}[0m [[33mstrategy.kt:104[0m]
[    31.428] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_White,Main.Pusher.Timer_Slide1_White):  Main.Pusher.Timer_Slide1_White.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.428] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_White,Main.Pusher.Timer_Slide1_White):  Main.Pusher.Timer_Slide1_White.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.428] (INFO) [37mcheckCache(Main.Pusher.Timer_Slide1_White,Main.Pusher.Timer_Slide1_White):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.428] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer_Slide1_White,Main.Pusher.Timer_Slide1_White):  Frame Main.Pusher.Timer_Slide1_White is syntactical equivalent to  Main.Pusher.Timer_Slide1_White [0m [[33mstrategy.kt:347[0m]
[    31.429] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide1_White,Main.Pusher.Timer_Slide1_White):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    31.429] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_Black,Main.Pusher.Timer_Slide2_Black):  Main.Pusher.Timer_Slide2_Black.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.429] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_Black,Main.Pusher.Timer_Slide2_Black):  Main.Pusher.Timer_Slide2_Black.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.429] (INFO) [37mcheckCache(Main.Pusher.Timer_Slide2_Black,Main.Pusher.Timer_Slide2_Black):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.429] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer_Slide2_Black,Main.Pusher.Timer_Slide2_Black):  Frame Main.Pusher.Timer_Slide2_Black is syntactical equivalent to  Main.Pusher.Timer_Slide2_Black [0m [[33mstrategy.kt:347[0m]
[    31.429] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_Black,Main.Pusher.Timer_Slide2_Black):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    31.429] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_Metal,Main.Pusher.Timer_Slide2_Metal):  Main.Pusher.Timer_Slide2_Metal.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    31.430] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_Metal,Main.Pusher.Timer_Slide2_Metal):  Main.Pusher.Timer_Slide2_Metal.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    31.430] (INFO) [37mcheckCache(Main.Pusher.Timer_Slide2_Metal,Main.Pusher.Timer_Slide2_Metal):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.430] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer_Slide2_Metal,Main.Pusher.Timer_Slide2_Metal):  Frame Main.Pusher.Timer_Slide2_Metal is syntactical equivalent to  Main.Pusher.Timer_Slide2_Metal [0m [[33mstrategy.kt:347[0m]
[    31.430] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_Metal,Main.Pusher.Timer_Slide2_Metal):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    31.431] (INFO) [37mproofEquivalence(Main.Mag,Main.Mag):  Main.Mag.0: (LoC:120) (Vars: [2] (11) => (9))[0m [[33mstrategy.kt:73[0m]
[    31.431] (INFO) [37mproofEquivalence(Main.Mag,Main.Mag):  Main.Mag.0: (LoC:121) (Vars: [2] (12) => (9))[0m [[33mstrategy.kt:74[0m]
[    31.432] (INFO) [37mcheckCache(Main.Mag,Main.Mag):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.432] (INFO) [37mproofBodyEquivalenceSource(Main.Mag,Main.Mag):  Frame Main.Mag is *not* syntactical equivalent to  Main.Mag[0m [[33mstrategy.kt:349[0m]
[    31.432] (INFO) [37mproofBodyEquivalenceSSA(Main.Mag,Main.Mag):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[    31.432] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Proof equivalence of frames Main.Mag and Main.Mag[0m [[33mstrategy.kt:385[0m]
[    31.432] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Translate Main.Mag to SMT[0m [[33mstrategy.kt:387[0m]
[    31.452] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Translate Main.Mag to SMT[0m [[33mstrategy.kt:392[0m]
[    31.469] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Create assertion[0m [[33mstrategy.kt:395[0m]
[    31.469] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Write SMT problem into output_1/Main.Mag_Main.Mag_equiv.smt[0m [[33mstrategy.kt:424[0m]
[    31.470] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Run z3 for Main.Mag_Main.Mag_equiv.smt[0m [[33mstrategy.kt:430[0m]
[    31.493] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag,Main.Mag):  Result of z3 is false for Main.Mag_Main.Mag_equiv.smt[0m [[33mstrategy.kt:433[0m]
[    31.498] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main.Mag,Main.Mag):  Add new regression verification contract[0m [[33mstrategy.kt:283[0m]
[    31.498] (INFO) [37mFrame Main.Mag_abstracted.0 to Main.Mag_abstracted.0[0m [[33mstrategy.kt:284[0m]
[    31.499] (INFO) [37m	Context: TRUE[0m [[33mstrategy.kt:285[0m]
[    31.499] (INFO) [37m	Input-Relation: old.Mag$CraneDown = new.Mag$CraneDown & old.Mag$CraneOnConveyor = new.Mag$CraneOnConveyor & old.Mag$CraneOnMagazin = new.Mag$CraneOnMagazin & old.Mag$CraneSucked = new.Mag$CraneSucked & old.Mag$CraneUp = new.Mag$CraneUp & old.Mag$SFCReset = new.Mag$SFCReset & old.Mag$SliderMovedOut = new.Mag$SliderMovedOut & old.Mag$SliderNotMovedOut = new.Mag$SliderNotMovedOut & old.Mag$StartButtonMagazin = new.Mag$StartButtonMagazin & old.Mag$StartVar = new.Mag$StartVar & old.Mag$WorkpieceReady = new.Mag$WorkpieceReady & old.Mag$CraneOnConveyor = (0sd16_16160 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_16260) & old.Mag$CraneOnMagazin = (0sd16_24290 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_24390) & old.Main$Mag_ccnt = new.Main$Mag_ccnt & old.Mag$CraneDown = new.Mag$CraneDown & old.Mag$CraneOnConveyor = new.Mag$CraneOnConveyor & old.Mag$CraneOnMagazin = new.Mag$CraneOnMagazin & old.Mag$CraneSucked = new.Mag$CraneSucked & old.Mag$CraneUp = new.Mag$CraneUp & old.Mag$SFCReset = new.Mag$SFCReset & old.Mag$SliderMovedOut = new.Mag$SliderMovedOut & old.Mag$SliderNotMovedOut = new.Mag$SliderNotMovedOut & old.Mag$StartButtonMagazin = new.Mag$StartButtonMagazin & old.Mag$StartVar = new.Mag$StartVar & old.Mag$WorkpieceReady = new.Mag$WorkpieceReady & old.Mag$CraneOnConveyor = (0sd16_16160 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_16260) & old.Mag$CraneOnMagazin = (0sd16_24290 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_24390)[0m [[33mstrategy.kt:286[0m]
[    31.500] (INFO) [37m	Output-Relation: old.Main$Mag_ccnt = new.Main$Mag_ccnt & old.Mag$CraneDown = new.Mag$CraneDown & old.Mag$CraneOnConveyor = new.Mag$CraneOnConveyor & old.Mag$CraneOnMagazin = new.Mag$CraneOnMagazin & old.Mag$CraneSucked = new.Mag$CraneSucked & old.Mag$CraneUp = new.Mag$CraneUp & old.Mag$SFCReset = new.Mag$SFCReset & old.Mag$SliderMovedOut = new.Mag$SliderMovedOut & old.Mag$SliderNotMovedOut = new.Mag$SliderNotMovedOut & old.Mag$StartButtonMagazin = new.Mag$StartButtonMagazin & old.Mag$StartVar = new.Mag$StartVar & old.Mag$WorkpieceReady = new.Mag$WorkpieceReady & old.Mag$CraneOnConveyor = (0sd16_16160 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_16260) & old.Mag$CraneOnMagazin = (0sd16_24290 < new.Main$Mag$0$Mag$AnalogCranePosition__input & new.Main$Mag$0$Mag$AnalogCranePosition__input < 0sd16_24390)[0m [[33mstrategy.kt:287[0m]
[    31.500] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main.Mag,Main.Mag):  Recursion for proofing abstracted body[0m [[33mstrategy.kt:290[0m]
[    31.500] (INFO) [37mproofEquivalence(Main.Mag_abstracted,Main.Mag_abstracted):  Main.Mag_abstracted.0: (LoC:121) (Vars: [2] (11) => (9))[0m [[33mstrategy.kt:73[0m]
[    31.501] (INFO) [37mproofEquivalence(Main.Mag_abstracted,Main.Mag_abstracted):  Main.Mag_abstracted.0: (LoC:122) (Vars: [2] (12) => (9))[0m [[33mstrategy.kt:74[0m]
[    31.501] (INFO) [37mcheckCache(Main.Mag_abstracted,Main.Mag_abstracted):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    31.501] (INFO) [37mproofBodyEquivalenceSource(Main.Mag_abstracted,Main.Mag_abstracted):  Frame Main.Mag_abstracted is *not* syntactical equivalent to  Main.Mag_abstracted[0m [[33mstrategy.kt:349[0m]
[    31.501] (INFO) [37mproofBodyEquivalenceSSA(Main.Mag_abstracted,Main.Mag_abstracted):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[    31.501] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Proof equivalence of frames Main.Mag_abstracted and Main.Mag_abstracted[0m [[33mstrategy.kt:385[0m]
[    31.501] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Translate Main.Mag_abstracted to SMT[0m [[33mstrategy.kt:387[0m]
[    31.527] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Translate Main.Mag_abstracted to SMT[0m [[33mstrategy.kt:392[0m]
[    31.541] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Create assertion[0m [[33mstrategy.kt:395[0m]
[    31.541] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Write SMT problem into output_1/Main.Mag_abstracted_Main.Mag_abstracted_equiv.smt[0m [[33mstrategy.kt:424[0m]
[    31.542] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Run z3 for Main.Mag_abstracted_Main.Mag_abstracted_equiv.smt[0m [[33mstrategy.kt:430[0m]
[    31.562] (INFO) [37mproofBodyEquivalenceSMT(Main.Mag_abstracted,Main.Mag_abstracted):  Result of z3 is false for Main.Mag_abstracted_Main.Mag_abstracted_equiv.smt[0m [[33mstrategy.kt:433[0m]
[    31.563] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main.Mag_abstracted,Main.Mag_abstracted):  declined, recursion protection triggered[0m [[33mstrategy.kt:203[0m]
[    31.602] (INFO) [37mproofBodyEquivalenceClassic(Main.Mag_abstracted,Main.Mag_abstracted):  Starting proof: output_1/Main.Mag_abstracted_Main.Mag_abstracted.smv, output_1/Main.Mag_abstracted_Main.Mag_abstracted.log[0m [[33mstrategy.kt:171[0m]
[    31.619] (INFO) [37mrunNuXmv: Run solver on output_1/Main.Mag_abstracted_Main.Mag_abstracted.smv[0m [[33mstrategy.kt:496[0m]
[    31.619] (INFO) [37m/home/weigl/share/nuXmv-1.1.1-Linux/bin/nuXmv -int /home/weigl/work/verifaps-lib/aps-rvt/examples/ppumod/12_13/output_1/Main.Mag_abstracted_Main.Mag_abstracted.smv[0m [[33mstrategy.kt:829[0m]
[    34.326] (INFO) [37mrunNuXmv: Solver finished for output_1/Main.Mag_abstracted_Main.Mag_abstracted.smv with edu.kit.iti.formal.smv.NuXMVOutput$Verified@5bf8fa12[0m [[33mstrategy.kt:500[0m]
[    34.326] (INFO) [37mproofBodyEquivalenceClassic(Main.Mag_abstracted,Main.Mag_abstracted):  Equality proven[0m [[33mstrategy.kt:176[0m]
[    34.326] (INFO) [37mproofEquivalence(Main.Mag_abstracted,Main.Mag_abstracted):  Timings: {cache=0, cmc=2763, mod=1, smt=61, src=0, ssa=0}[0m [[33mstrategy.kt:104[0m]
[    34.326] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main.Mag,Main.Mag):  Proving sub-frames: [0m [[33mstrategy.kt:308[0m]
[    34.327] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main.Mag,Main.Mag):  proved equality of subframes[0m [[33mstrategy.kt:326[0m]
[    34.327] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main.Mag,Main.Mag):  Equality proven[0m [[33mstrategy.kt:229[0m]
[    34.327] (INFO) [37mproofEquivalence(Main.Mag,Main.Mag):  Timings: {cache=1, mod=2834, smt=61, src=0, ssa=0}[0m [[33mstrategy.kt:104[0m]
[    34.327] (INFO) [37mproofEquivalence(Main.Conveyor.Timer_Conveyor_Test,Main.Conveyor.Timer_Conveyor_Test):  Main.Conveyor.Timer_Conveyor_Test.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.327] (INFO) [37mproofEquivalence(Main.Conveyor.Timer_Conveyor_Test,Main.Conveyor.Timer_Conveyor_Test):  Main.Conveyor.Timer_Conveyor_Test.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.327] (INFO) [37mcheckCache(Main.Conveyor.Timer_Conveyor_Test,Main.Conveyor.Timer_Conveyor_Test):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.327] (INFO) [37mproofBodyEquivalenceSource(Main.Conveyor.Timer_Conveyor_Test,Main.Conveyor.Timer_Conveyor_Test):  Frame Main.Conveyor.Timer_Conveyor_Test is syntactical equivalent to  Main.Conveyor.Timer_Conveyor_Test [0m [[33mstrategy.kt:347[0m]
[    34.327] (INFO) [37mproofEquivalence(Main.Conveyor.Timer_Conveyor_Test,Main.Conveyor.Timer_Conveyor_Test):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    34.328] (INFO) [37mproofEquivalence(Main.Pusher.Timer2,Main.Pusher.Timer2):  Main.Pusher.Timer2.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.328] (INFO) [37mproofEquivalence(Main.Pusher.Timer2,Main.Pusher.Timer2):  Main.Pusher.Timer2.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.328] (INFO) [37mcheckCache(Main.Pusher.Timer2,Main.Pusher.Timer2):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.328] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer2,Main.Pusher.Timer2):  Frame Main.Pusher.Timer2 is syntactical equivalent to  Main.Pusher.Timer2 [0m [[33mstrategy.kt:347[0m]
[    34.328] (INFO) [37mproofEquivalence(Main.Pusher.Timer2,Main.Pusher.Timer2):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    34.328] (INFO) [37mproofEquivalence(Main.Pusher.Timer3,Main.Pusher.Timer3):  Main.Pusher.Timer3.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.328] (INFO) [37mproofEquivalence(Main.Pusher.Timer3,Main.Pusher.Timer3):  Main.Pusher.Timer3.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.329] (INFO) [37mcheckCache(Main.Pusher.Timer3,Main.Pusher.Timer3):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.329] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer3,Main.Pusher.Timer3):  Frame Main.Pusher.Timer3 is syntactical equivalent to  Main.Pusher.Timer3 [0m [[33mstrategy.kt:347[0m]
[    34.329] (INFO) [37mproofEquivalence(Main.Pusher.Timer3,Main.Pusher.Timer3):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    34.333] (INFO) [37mproofEquivalence(Main.Pusher,Main.Pusher):  Main.Pusher.0: (LoC:1072) (Vars: [61] (14) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.336] (INFO) [37mproofEquivalence(Main.Pusher,Main.Pusher):  Main.Pusher.0: (LoC:1072) (Vars: [61] (14) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.336] (INFO) [37mcheckCache(Main.Pusher,Main.Pusher):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.339] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher,Main.Pusher):  Frame Main.Pusher is syntactical equivalent to  Main.Pusher [0m [[33mstrategy.kt:347[0m]
[    34.340] (INFO) [37mproofEquivalence(Main.Pusher,Main.Pusher):  Timings: {cache=0, src=3}[0m [[33mstrategy.kt:104[0m]
[    34.340] (INFO) [37mproofEquivalence(Main.Stamp.Timer,Main.Stamp.Timer):  Main.Stamp.Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.340] (INFO) [37mproofEquivalence(Main.Stamp.Timer,Main.Stamp.Timer):  Main.Stamp.Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.340] (INFO) [37mcheckCache(Main.Stamp.Timer,Main.Stamp.Timer):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.340] (INFO) [37mproofBodyEquivalenceSource(Main.Stamp.Timer,Main.Stamp.Timer):  Frame Main.Stamp.Timer is syntactical equivalent to  Main.Stamp.Timer [0m [[33mstrategy.kt:347[0m]
[    34.340] (INFO) [37mproofEquivalence(Main.Stamp.Timer,Main.Stamp.Timer):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    34.341] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_White,Main.Pusher.Timer_Slide2_White):  Main.Pusher.Timer_Slide2_White.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.341] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_White,Main.Pusher.Timer_Slide2_White):  Main.Pusher.Timer_Slide2_White.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.341] (INFO) [37mcheckCache(Main.Pusher.Timer_Slide2_White,Main.Pusher.Timer_Slide2_White):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.341] (INFO) [37mproofBodyEquivalenceSource(Main.Pusher.Timer_Slide2_White,Main.Pusher.Timer_Slide2_White):  Frame Main.Pusher.Timer_Slide2_White is syntactical equivalent to  Main.Pusher.Timer_Slide2_White [0m [[33mstrategy.kt:347[0m]
[    34.341] (INFO) [37mproofEquivalence(Main.Pusher.Timer_Slide2_White,Main.Pusher.Timer_Slide2_White):  Timings: {cache=0, src=0}[0m [[33mstrategy.kt:104[0m]
[    34.342] (INFO) [37mproofEquivalence(Main.Stamp,Main.Stamp):  Main.Stamp.0: (LoC:201) (Vars: [8] (13) => (7))[0m [[33mstrategy.kt:73[0m]
[    34.343] (INFO) [37mproofEquivalence(Main.Stamp,Main.Stamp):  Main.Stamp.0: (LoC:202) (Vars: [8] (14) => (7))[0m [[33mstrategy.kt:74[0m]
[    34.343] (INFO) [37mcheckCache(Main.Stamp,Main.Stamp):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.343] (INFO) [37mproofBodyEquivalenceSource(Main.Stamp,Main.Stamp):  Frame Main.Stamp is *not* syntactical equivalent to  Main.Stamp[0m [[33mstrategy.kt:349[0m]
[    34.344] (INFO) [37mproofBodyEquivalenceSSA(Main.Stamp,Main.Stamp):  Skipped because `disableProofBodyEquivalenceSSA` is set[0m [[33mstrategy.kt:357[0m]
[    34.344] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Proof equivalence of frames Main.Stamp and Main.Stamp[0m [[33mstrategy.kt:385[0m]
[    34.344] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Translate Main.Stamp to SMT[0m [[33mstrategy.kt:387[0m]
[    34.373] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Translate Main.Stamp to SMT[0m [[33mstrategy.kt:392[0m]
[    34.398] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Create assertion[0m [[33mstrategy.kt:395[0m]
[    34.399] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Write SMT problem into output_1/Main.Stamp_Main.Stamp_equiv.smt[0m [[33mstrategy.kt:424[0m]
[    34.399] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Run z3 for Main.Stamp_Main.Stamp_equiv.smt[0m [[33mstrategy.kt:430[0m]
[    34.421] (INFO) [37mproofBodyEquivalenceSMT(Main.Stamp,Main.Stamp):  Result of z3 is false for Main.Stamp_Main.Stamp_equiv.smt[0m [[33mstrategy.kt:433[0m]
[    34.428] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main.Stamp,Main.Stamp):  Add new regression verification contract[0m [[33mstrategy.kt:283[0m]
[    34.428] (INFO) [37mFrame Main.Stamp_abstracted.0 to Main.Stamp_abstracted.0[0m [[33mstrategy.kt:284[0m]
[    34.428] (INFO) [37m	Context: TRUE[0m [[33mstrategy.kt:285[0m]
[    34.429] (INFO) [37m	Input-Relation: old.Stamp$CraneDown = new.Stamp$CraneDown & old.Stamp$CraneOnConveyor = new.Stamp$CraneOnConveyor & old.Stamp$CraneOnConveyor = (0sd16_16160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_16260) & old.Stamp$CranePositionStamp = (0sd16_8160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_8260) & old.Stamp$CranePositionStamp = new.Stamp$CranePositionStamp & old.Stamp$CraneSucked = new.Stamp$CraneSucked & old.Stamp$CraneUp = new.Stamp$CraneUp & old.Stamp$SFCReset = new.Stamp$SFCReset & old.Stamp$StampLowered = new.Stamp$StampLowered & old.Stamp$StampSliderFilled = new.Stamp$StampSliderFilled & old.Stamp$StampSliderSensorMovedIn = new.Stamp$StampSliderSensorMovedIn & old.Stamp$StampSliderSensorMovedOut = new.Stamp$StampSliderSensorMovedOut & old.Stamp$StampStartButton = new.Stamp$StampStartButton & old.Stamp$StampUp = new.Stamp$StampUp & old.Stamp$StartVar = new.Stamp$StartVar & old.Main$Stamp$Timer$1$Stamp$Timer$ET__random = new.Main$Stamp$Timer$1$Stamp$Timer$ET__random & old.Main$Stamp$Timer$1$Stamp$Timer$Q__random = new.Main$Stamp$Timer$1$Stamp$Timer$Q__random & old.Main$Stamp$Timer_ccnt = new.Main$Stamp$Timer_ccnt & old.Main$Stamp$Timer$1$Stamp$Timer$IN__input = new.Main$Stamp$Timer$1$Stamp$Timer$IN__input & old.Main$Stamp$Timer$1$Stamp$Timer$PT__input = new.Main$Stamp$Timer$1$Stamp$Timer$PT__input & old.Main$Stamp_ccnt = new.Main$Stamp_ccnt & old.Stamp$CraneDown = new.Stamp$CraneDown & old.Stamp$CraneOnConveyor = new.Stamp$CraneOnConveyor & old.Stamp$CraneOnConveyor = (0sd16_16160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_16260) & old.Stamp$CranePositionStamp = (0sd16_8160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_8260) & old.Stamp$CranePositionStamp = new.Stamp$CranePositionStamp & old.Stamp$CraneSucked = new.Stamp$CraneSucked & old.Stamp$CraneUp = new.Stamp$CraneUp & old.Stamp$SFCReset = new.Stamp$SFCReset & old.Stamp$StampLowered = new.Stamp$StampLowered & old.Stamp$StampSliderFilled = new.Stamp$StampSliderFilled & old.Stamp$StampSliderSensorMovedIn = new.Stamp$StampSliderSensorMovedIn & old.Stamp$StampSliderSensorMovedOut = new.Stamp$StampSliderSensorMovedOut & old.Stamp$StampStartButton = new.Stamp$StampStartButton & old.Stamp$StampUp = new.Stamp$StampUp & old.Stamp$StartVar = new.Stamp$StartVar & old.Main$Stamp$Timer$1$Stamp$Timer$ET__random = new.Main$Stamp$Timer$1$Stamp$Timer$ET__random & old.Main$Stamp$Timer$1$Stamp$Timer$Q__random = new.Main$Stamp$Timer$1$Stamp$Timer$Q__random[0m [[33mstrategy.kt:286[0m]
[    34.429] (INFO) [37m	Output-Relation: old.Main$Stamp$Timer_ccnt = new.Main$Stamp$Timer_ccnt & old.Main$Stamp$Timer$1$Stamp$Timer$IN__input = new.Main$Stamp$Timer$1$Stamp$Timer$IN__input & old.Main$Stamp$Timer$1$Stamp$Timer$PT__input = new.Main$Stamp$Timer$1$Stamp$Timer$PT__input & old.Main$Stamp_ccnt = new.Main$Stamp_ccnt & old.Stamp$CraneDown = new.Stamp$CraneDown & old.Stamp$CraneOnConveyor = new.Stamp$CraneOnConveyor & old.Stamp$CraneOnConveyor = (0sd16_16160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_16260) & old.Stamp$CranePositionStamp = (0sd16_8160 < new.Main$Stamp$0$Stamp$AnalogCranePosition__input & new.Main$Stamp$0$Stamp$AnalogCranePosition__input < 0sd16_8260) & old.Stamp$CranePositionStamp = new.Stamp$CranePositionStamp & old.Stamp$CraneSucked = new.Stamp$CraneSucked & old.Stamp$CraneUp = new.Stamp$CraneUp & old.Stamp$SFCReset = new.Stamp$SFCReset & old.Stamp$StampLowered = new.Stamp$StampLowered & old.Stamp$StampSliderFilled = new.Stamp$StampSliderFilled & old.Stamp$StampSliderSensorMovedIn = new.Stamp$StampSliderSensorMovedIn & old.Stamp$StampSliderSensorMovedOut = new.Stamp$StampSliderSensorMovedOut & old.Stamp$StampStartButton = new.Stamp$StampStartButton & old.Stamp$StampUp = new.Stamp$StampUp & old.Stamp$StartVar = new.Stamp$StartVar & old.Main$Stamp$Timer$1$Stamp$Timer$ET__random = new.Main$Stamp$Timer$1$Stamp$Timer$ET__random & old.Main$Stamp$Timer$1$Stamp$Timer$Q__random = new.Main$Stamp$Timer$1$Stamp$Timer$Q__random[0m [[33mstrategy.kt:287[0m]
[    34.429] (INFO) [37mproofBodyEquivalenceWithAbstractionBody(Main.Stamp,Main.Stamp):  Recursion for proofing abstracted body[0m [[33mstrategy.kt:290[0m]
[    34.430] (INFO) [37mproofEquivalence(Main.Stamp_abstracted,Main.Stamp_abstracted):  Main.Stamp_abstracted.0: (LoC:187) (Vars: [8] (13) => (7))[0m [[33mstrategy.kt:73[0m]
[    34.431] (INFO) [37mproofEquivalence(Main.Stamp_abstracted,Main.Stamp_abstracted):  Main.Stamp_abstracted.0: (LoC:188) (Vars: [8] (14) => (7))[0m [[33mstrategy.kt:74[0m]
[    34.431] (INFO) [37mproofEquivalence(Main.Stamp_abstracted,Main.Stamp_abstracted):  Proven by assumption[0m [[33mstrategy.kt:79[0m]
[    34.431] (INFO) [37mproofEquivalence(Main.Stamp_abstracted,Main.Stamp_abstracted):  Timings: {}[0m [[33mstrategy.kt:104[0m]
[    34.431] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main.Stamp,Main.Stamp):  Proving sub-frames: Timer=Timer[0m [[33mstrategy.kt:308[0m]
[    34.431] (INFO) [37mproofEquivalence(Main.Stamp.Timer,Main.Stamp.Timer):  Main.Stamp.Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:73[0m]
[    34.431] (INFO) [37mproofEquivalence(Main.Stamp.Timer,Main.Stamp.Timer):  Main.Stamp.Timer.0: (LoC:13) (Vars: [0] (2) => (2))[0m [[33mstrategy.kt:74[0m]
[    34.431] (INFO) [37mcheckCache(Main.Stamp.Timer,Main.Stamp.Timer):  Cache miss equiv. proof of null with null[0m [[33mstrategy.kt:124[0m]
[    34.432] (INFO) [37mproofBodyEquivalenceSource(Main.Stamp.Timer,Main.Stamp.Timer):  Frame Main.Stamp.Timer is syntactical equivalent to  Main.Stamp.Timer [0m [[33mstrategy.kt:347[0m]
[    34.432] (INFO) [37mproofEquivalence(Main.Stamp.Timer,Main.Stamp.Timer):  Timings: {cache=0, src=1}[0m [[33mstrategy.kt:104[0m]
[    34.432] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main.Stamp,Main.Stamp):  proved equality of subframes[0m [[33mstrategy.kt:326[0m]
[    34.432] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main.Stamp,Main.Stamp):  Equality proven[0m [[33mstrategy.kt:229[0m]
[    34.432] (INFO) [37mproofEquivalence(Main.Stamp,Main.Stamp):  Timings: {cache=0, mod=11, smt=77, src=0, ssa=1}[0m [[33mstrategy.kt:104[0m]
[    34.432] (INFO) [37mproofBodyEquivalenceWithAbstractionSubFrames(Main,Main):  proved equality of subframes[0m [[33mstrategy.kt:326[0m]
[    34.432] (INFO) [37mproofBodyEquivalenceWithAbstraction(Main,Main):  Equality proven[0m [[33mstrategy.kt:229[0m]
[    34.432] (INFO) [37mproofEquivalence(Main,Main):  Timings: {cache=0, mod=24867, smt=558, src=0, ssa=0}[0m [[33mstrategy.kt:104[0m]
[    34.432] (INFO) [37mProof result: true[0m [[33mmodularization.kt:219[0m]
