Attachment 1474269 Details for Bug 1596338 – automate.log

automate.log

automation.log (text/plain), 16.00 KB, created by Yadnyawalk Tale on 2018-08-08 10:55:16 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Yadnyawalk Tale

Created: 2018-08-08 10:55:16 UTC

Size: 16.00 KB

patch

obsolete

>
>
>[----] I, [2018-08-08T06:50:22.477570 #2250:3796b90]  INFO -- : Instantiating [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create]
>[----] I, [2018-08-08T06:50:22.500416 #2250:3796b90]  INFO -- : Updated namespace [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:50:22.581064 #2250:3796b90]  INFO -- : Updated namespace [SYSTEM/PROCESS/parse_provider_category  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:50:22.587213 #2250:3796b90]  INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-08-08T06:50:22.587656 #2250:3796b90]  INFO -- : Setting provider_category to: 
>[----] I, [2018-08-08T06:50:22.588209 #2250:3796b90]  INFO -- : Following Relationship [miqaedb:/System/Request/Call_Instance#create]
>[----] I, [2018-08-08T06:50:22.604470 #2250:3796b90]  INFO -- : Updated namespace [miqaedb:/System/Request/Call_Instance#create  ManageIQ/System]
>[----] I, [2018-08-08T06:50:22.614525 #2250:3796b90]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:50:22.632138 #2250:3796b90]  INFO -- : Processing State=[state1]
>[----] I, [2018-08-08T06:50:22.632428 #2250:3796b90]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:50:22.639165 #2250:3796b90]  INFO -- : Updated namespace [testNS/ytale_Class/meth1  ytale_domain/testNS]
>[----] I, [2018-08-08T06:50:22.646023 #2250:3796b90]  INFO -- : Invoking [inline] method [/ytale_domain/testNS/ytale_Class/meth1] with inputs [{}]
>[----] I, [2018-08-08T06:50:22.647762 #2250:3796b90]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Starting 
>[----] I, [2018-08-08T06:50:23.067681 #2250:7b8ccdc]  INFO -- : <AEMethod meth1> method started
>[----] I, [2018-08-08T06:50:23.068963 #2250:7b8ccdc]  INFO -- : <AEMethod meth1> Retry Count Value : 0
>[----] I, [2018-08-08T06:50:23.106391 #2250:3796b90]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Ending
>[----] I, [2018-08-08T06:50:23.106516 #2250:3796b90]  INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-08-08T06:50:23.106962 #2250:3796b90]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:50:23.107087 #2250:3796b90]  INFO -- : Processed State=[state1] with Result=[retry]
>[----] I, [2018-08-08T06:50:23.107402 #2250:3796b90]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:50:23.108289 #2250:3796b90]  INFO -- : Followed  Relationship [miqaedb:/System/Request/Call_Instance#create]
>
>
>[----] I, [2018-08-08T06:50:42.631371 #2250:3797810]  INFO -- : Instantiating [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=1&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create]
>[----] I, [2018-08-08T06:50:42.660857 #2250:3797810]  INFO -- : Updated namespace [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%201%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=1&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:50:42.754690 #2250:3797810]  INFO -- : Updated namespace [SYSTEM/PROCESS/parse_provider_category  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:50:42.761632 #2250:3797810]  INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-08-08T06:50:42.762230 #2250:3797810]  INFO -- : Setting provider_category to: 
>[----] I, [2018-08-08T06:50:42.762941 #2250:3797810]  INFO -- : Following Relationship [miqaedb:/System/Request/Call_Instance#create]
>[----] I, [2018-08-08T06:50:42.781618 #2250:3797810]  INFO -- : Updated namespace [miqaedb:/System/Request/Call_Instance#create  ManageIQ/System]
>[----] I, [2018-08-08T06:50:42.792096 #2250:3797810]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:50:42.809633 #2250:3797810]  INFO -- : Processing State=[state1]
>[----] I, [2018-08-08T06:50:42.809849 #2250:3797810]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:50:42.817617 #2250:3797810]  INFO -- : Updated namespace [testNS/ytale_Class/meth1  ytale_domain/testNS]
>[----] I, [2018-08-08T06:50:42.824376 #2250:3797810]  INFO -- : Invoking [inline] method [/ytale_domain/testNS/ytale_Class/meth1] with inputs [{}]
>[----] I, [2018-08-08T06:50:42.826092 #2250:3797810]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Starting 
>[----] I, [2018-08-08T06:50:43.325411 #2250:570170]  INFO -- : <AEMethod meth1> method started
>[----] I, [2018-08-08T06:50:43.327634 #2250:570170]  INFO -- : <AEMethod meth1> Retry Count Value : 1
>[----] I, [2018-08-08T06:50:43.360024 #2250:3797810]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Ending
>[----] I, [2018-08-08T06:50:43.360204 #2250:3797810]  INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-08-08T06:50:43.360634 #2250:3797810]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:50:43.360774 #2250:3797810]  INFO -- : Processed State=[state1] with Result=[retry]
>[----] I, [2018-08-08T06:50:43.361219 #2250:3797810]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:50:43.362046 #2250:3797810]  INFO -- : Followed  Relationship [miqaedb:/System/Request/Call_Instance#create]
>
>
>[----] I, [2018-08-08T06:51:01.711220 #2250:37941c4]  INFO -- : Instantiating [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%202%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=2&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create]
>[----] I, [2018-08-08T06:51:01.741137 #2250:37941c4]  INFO -- : Updated namespace [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%202%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=2&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:51:01.840067 #2250:37941c4]  INFO -- : Updated namespace [SYSTEM/PROCESS/parse_provider_category  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:51:01.847217 #2250:37941c4]  INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-08-08T06:51:01.847777 #2250:37941c4]  INFO -- : Setting provider_category to: 
>[----] I, [2018-08-08T06:51:01.848426 #2250:37941c4]  INFO -- : Following Relationship [miqaedb:/System/Request/Call_Instance#create]
>[----] I, [2018-08-08T06:51:01.865726 #2250:37941c4]  INFO -- : Updated namespace [miqaedb:/System/Request/Call_Instance#create  ManageIQ/System]
>[----] I, [2018-08-08T06:51:01.874143 #2250:37941c4]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:51:01.889861 #2250:37941c4]  INFO -- : Processing State=[state1]
>[----] I, [2018-08-08T06:51:01.890061 #2250:37941c4]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:51:01.896256 #2250:37941c4]  INFO -- : Updated namespace [testNS/ytale_Class/meth1  ytale_domain/testNS]
>[----] I, [2018-08-08T06:51:01.902749 #2250:37941c4]  INFO -- : Invoking [inline] method [/ytale_domain/testNS/ytale_Class/meth1] with inputs [{}]
>[----] I, [2018-08-08T06:51:01.904579 #2250:37941c4]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Starting 
>[----] I, [2018-08-08T06:51:02.306363 #2250:9b4feac]  INFO -- : <AEMethod meth1> method started
>[----] I, [2018-08-08T06:51:02.307649 #2250:9b4feac]  INFO -- : <AEMethod meth1> Retry Count Value : 2
>[----] I, [2018-08-08T06:51:02.338208 #2250:37941c4]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Ending
>[----] I, [2018-08-08T06:51:02.338380 #2250:37941c4]  INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-08-08T06:51:02.338909 #2250:37941c4]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:51:02.339011 #2250:37941c4]  INFO -- : Processed State=[state1] with Result=[retry]
>[----] I, [2018-08-08T06:51:02.339332 #2250:37941c4]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:51:02.339996 #2250:37941c4]  INFO -- : Followed  Relationship [miqaedb:/System/Request/Call_Instance#create]
>
>
>[----] I, [2018-08-08T06:51:07.353691 #2250:3796ce4]  INFO -- : Instantiating [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%203%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=3&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create]
>[----] I, [2018-08-08T06:51:07.377950 #2250:3796ce4]  INFO -- : Updated namespace [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%203%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=3&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:51:07.461364 #2250:3796ce4]  INFO -- : Updated namespace [SYSTEM/PROCESS/parse_provider_category  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:51:07.468331 #2250:3796ce4]  INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-08-08T06:51:07.469218 #2250:3796ce4]  INFO -- : Setting provider_category to: 
>[----] I, [2018-08-08T06:51:07.469920 #2250:3796ce4]  INFO -- : Following Relationship [miqaedb:/System/Request/Call_Instance#create]
>[----] I, [2018-08-08T06:51:07.487701 #2250:3796ce4]  INFO -- : Updated namespace [miqaedb:/System/Request/Call_Instance#create  ManageIQ/System]
>[----] I, [2018-08-08T06:51:07.497029 #2250:3796ce4]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:51:07.515799 #2250:3796ce4]  INFO -- : Processing State=[state1]
>[----] I, [2018-08-08T06:51:07.516113 #2250:3796ce4]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:51:07.524374 #2250:3796ce4]  INFO -- : Updated namespace [testNS/ytale_Class/meth1  ytale_domain/testNS]
>[----] I, [2018-08-08T06:51:07.532455 #2250:3796ce4]  INFO -- : Invoking [inline] method [/ytale_domain/testNS/ytale_Class/meth1] with inputs [{}]
>[----] I, [2018-08-08T06:51:07.534538 #2250:3796ce4]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Starting 
>[----] I, [2018-08-08T06:51:08.076628 #2250:57a620]  INFO -- : <AEMethod meth1> method started
>[----] I, [2018-08-08T06:51:08.078228 #2250:57a620]  INFO -- : <AEMethod meth1> Retry Count Value : 3
>[----] I, [2018-08-08T06:51:08.110033 #2250:3796ce4]  INFO -- : <AEMethod [/ytale_domain/testNS/ytale_Class/meth1]> Ending
>[----] I, [2018-08-08T06:51:08.110294 #2250:3796ce4]  INFO -- : Method exited with rc=MIQ_OK
>[----] I, [2018-08-08T06:51:08.110749 #2250:3796ce4]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/ytale_Class/meth1_instance#create]
>[----] I, [2018-08-08T06:51:08.110882 #2250:3796ce4]  INFO -- : Processed State=[state1] with Result=[retry]
>[----] I, [2018-08-08T06:51:08.111272 #2250:3796ce4]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:51:08.112273 #2250:3796ce4]  INFO -- : Followed  Relationship [miqaedb:/System/Request/Call_Instance#create]
>
>
>
>[----] I, [2018-08-08T06:51:20.474544 #2250:379441c]  INFO -- : Instantiating [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%204%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=4&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create]
>[----] I, [2018-08-08T06:51:20.496462 #2250:379441c]  INFO -- : Updated namespace [/SYSTEM/PROCESS/Request?MiqServer%3A%3Amiq_server=1&User%3A%3Auser=1&ae_state=state1&ae_state_previous=---%0A%22%2Fytale_domain%2FtestNS%2FStateMachine%2Fsm_instance%22%3A%0A%20%20ae_state%3A%20state1%0A%20%20ae_state_retries%3A%204%0A%20%20ae_state_started%3A%202018-08-08%2010%3A50%3A22%20UTC%0A%20%20ae_state_max_retries%3A%203%0A&ae_state_retries=4&class=StateMachine&instance=sm_instance&message=create&namespace=ytale_domain%2FtestNS&object_name=Request&request=Call_Instance#create  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:51:20.573225 #2250:379441c]  INFO -- : Updated namespace [SYSTEM/PROCESS/parse_provider_category  ManageIQ/SYSTEM]
>[----] I, [2018-08-08T06:51:20.579129 #2250:379441c]  INFO -- : Invoking [builtin] method [/ManageIQ/System/Process/parse_provider_category] with inputs [{}]
>[----] I, [2018-08-08T06:51:20.579600 #2250:379441c]  INFO -- : Setting provider_category to: 
>[----] I, [2018-08-08T06:51:20.580006 #2250:379441c]  INFO -- : Following Relationship [miqaedb:/System/Request/Call_Instance#create]
>[----] I, [2018-08-08T06:51:20.594677 #2250:379441c]  INFO -- : Updated namespace [miqaedb:/System/Request/Call_Instance#create  ManageIQ/System]
>[----] I, [2018-08-08T06:51:20.602054 #2250:379441c]  INFO -- : Following Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:51:20.616595 #2250:379441c]  INFO -- : Processing State=[state1]
>[----] E, [2018-08-08T06:51:20.616767 #2250:379441c] ERROR -- : State=<state1> running  raised exception: <number of retries <4> exceeded maximum of <3>>
>[----] W, [2018-08-08T06:51:20.616892 #2250:379441c]  WARN -- : Error in State=[state1]
>[----] I, [2018-08-08T06:51:20.617018 #2250:379441c]  INFO -- : Followed  Relationship [miqaedb:/ytale_domain/testNS/StateMachine/sm_instance#create]
>[----] I, [2018-08-08T06:51:20.617376 #2250:379441c]  INFO -- : Followed  Relationship [miqaedb:/System/Request/Call_Instance#create]
>
>
>
>

Actions: View

Attachments on bug 1596338: 1474269