[ N 2022-06-22 14:49:43.5898 87639/T1 age/Wat/WatchdogMain.cpp:1373 ]: Starting Passenger watchdog... [ N 2022-06-22 14:49:43.6216 87642/T1 age/Cor/CoreMain.cpp:1340 ]: Starting Passenger core... [ N 2022-06-22 14:49:43.6217 87642/T1 age/Cor/CoreMain.cpp:256 ]: Passenger core running in multi-application mode. [ N 2022-06-22 14:49:43.6362 87642/T1 age/Cor/CoreMain.cpp:1015 ]: Passenger core online, PID 87642 [ E 2022-06-22 14:49:45.8980 87642/T6 age/Cor/SecurityUpdateChecker.h:507 ]: Security update check failed: SSL connect error while connecting to https://securitycheck.phusionpassenger.com/v1/check.json (if this error persists check your connection security or try upgrading Passenger) (next check in 24 hours) App 87662 output: [2022-06-22 14:49:51 -0500 ] INFO "method=GET path=/pun/sys/dashboard/ format=html controller=DashboardController action=index status=200 duration=250.40 view=159.14" App 87662 output: [DEPRECATED] `Bundler.with_clean_env` has been deprecated in favor of `Bundler.with_unbundled_env`. If you instead want the environment before bundler was originally loaded, use `Bundler.with_original_env` (called at /var/www/ood/apps/sys/dashboard/app/apps/ood_app.rb:242) App 87662 output: [2022-06-22 14:49:59 -0500 ] INFO "method=GET path=/pun/sys/dashboard/apps/show/activejobs format=html controller=AppsController action=show status=302 duration=7.06 view=0.00 location=https://hpcood-test.mdanderson.edu/pun/sys/dashboard/activejobs" App 87662 output: [2022-06-22 14:49:59 -0500 ] INFO "method=GET path=/pun/sys/dashboard/activejobs format=html controller=ActiveJobsController action=index status=200 duration=27.29 view=16.85" App 87662 output: [2022-06-22 14:49:59 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-u\", \"emsisson\", \"-a\", \"-w\", \"-W\"]" App 87662 output: [2022-06-22 14:50:39 -0500 ] INFO "method=GET path=/pun/sys/dashboard/activejobs.json format=json controller=ActiveJobsController action=index status=200 duration=40126.94 view=0.00" App 87662 output: [2022-06-22 14:52:49 -0500 ] INFO "method=GET path=/pun/sys/dashboard/apps/show/myjobs format=html controller=AppsController action=show status=302 duration=8698.18 view=0.00 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs" App 87810 output: /opt/ood/ondemand/root/usr/share/gems/2.7/ondemand/2.0.23/gems/activerecord-5.2.6/lib/active_record/connection_adapters/sqlite3_adapter.rb:32: warning: rb_check_safe_obj will be removed in Ruby 3.0 App 87810 output: [2022-06-22 14:52:52 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 14:52:52 -0500 ] DEBUG "\e[1m\e[35mSQL (27.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".\"id\" AS t0_r0, \"workflows\".\"created_at\" AS t0_r1, \"workflows\".\"updated_at\" AS t0_r2, \"workflows\".\"job_attrs\" AS t0_r3, \"workflows\".\"name\" AS t0_r4, \"workflows\".\"batch_host\" AS t0_r5, \"workflows\".\"staged_dir\" AS t0_r6, \"workflows\".\"script_name\" AS t0_r7, \"jobs\".\"id\" AS t1_r0, \"jobs\".\"workflow_id\" AS t1_r1, \"jobs\".\"status\" AS t1_r2, \"jobs\".\"job_cache\" AS t1_r3, \"jobs\".\"created_at\" AS t1_r4, \"jobs\".\"updated_at\" AS t1_r5 FROM \"workflows\" LEFT OUTER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\"\e[0m" App 87810 output: [2022-06-22 14:52:52 -0500 ] INFO "method=GET path=/pun/sys/myjobs/ format=html controller=WorkflowsController action=index status=200 duration=517.44 view=40.64 db=282.14" App 87810 output: [2022-06-22 14:53:06 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/new format=html controller=WorkflowsController action=new status=200 duration=96.56 view=62.35 db=0.00" App 87662 output: [2022-06-22 14:53:07 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/var/www/ood/apps/sys/myjobs/templates/example format=html controller=FilesController action=fs status=200 duration=67.86 view=17.81" App 87662 output: [2022-06-22 14:53:29 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/var/www/ood/apps/sys/myjobs/templates/example format=html controller=FilesController action=fs status=200 duration=11.51 view=5.98" App 87662 output: [2022-06-22 14:53:29 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/var/www/ood/apps/sys/myjobs/templates/example format=html controller=FilesController action=fs status=200 duration=10.55 view=5.68" App 87810 output: [2022-06-22 14:54:17 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (38.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 14:54:17 -0500 ] DEBUG "\e[1m\e[35mSQL (31.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".\"id\" AS t0_r0, \"workflows\".\"created_at\" AS t0_r1, \"workflows\".\"updated_at\" AS t0_r2, \"workflows\".\"job_attrs\" AS t0_r3, \"workflows\".\"name\" AS t0_r4, \"workflows\".\"batch_host\" AS t0_r5, \"workflows\".\"staged_dir\" AS t0_r6, \"workflows\".\"script_name\" AS t0_r7, \"jobs\".\"id\" AS t1_r0, \"jobs\".\"workflow_id\" AS t1_r1, \"jobs\".\"status\" AS t1_r2, \"jobs\".\"job_cache\" AS t1_r3, \"jobs\".\"created_at\" AS t1_r4, \"jobs\".\"updated_at\" AS t1_r5 FROM \"workflows\" LEFT OUTER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\"\e[0m" App 87810 output: [2022-06-22 14:54:17 -0500 ] INFO "method=GET path=/pun/sys/myjobs/ format=html controller=WorkflowsController action=index status=200 duration=78.01 view=2.39 db=70.18" App 87810 output: [2022-06-22 14:54:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 14:54:28 -0500 ] DEBUG "\e[1m\e[35mSQL (28.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".\"id\" AS t0_r0, \"workflows\".\"created_at\" AS t0_r1, \"workflows\".\"updated_at\" AS t0_r2, \"workflows\".\"job_attrs\" AS t0_r3, \"workflows\".\"name\" AS t0_r4, \"workflows\".\"batch_host\" AS t0_r5, \"workflows\".\"staged_dir\" AS t0_r6, \"workflows\".\"script_name\" AS t0_r7, \"jobs\".\"id\" AS t1_r0, \"jobs\".\"workflow_id\" AS t1_r1, \"jobs\".\"status\" AS t1_r2, \"jobs\".\"job_cache\" AS t1_r3, \"jobs\".\"created_at\" AS t1_r4, \"jobs\".\"updated_at\" AS t1_r5 FROM \"workflows\" LEFT OUTER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\"\e[0m" App 87810 output: [2022-06-22 14:54:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/ format=html controller=WorkflowsController action=index status=200 duration=67.14 view=2.71 db=58.81" App 87810 output: [2022-06-22 14:54:43 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/new format=html controller=WorkflowsController action=new status=200 duration=11.38 view=7.35 db=0.00" App 87662 output: [2022-06-22 14:54:43 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/var/www/ood/apps/sys/myjobs/templates/example format=html controller=FilesController action=fs status=200 duration=9.87 view=4.73" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 14:55:02 -0500 ] INFO "execve = [\"rsync -r --exclude='manifest.yml' /var/www/ood/apps/sys/myjobs/templates/example/ /home/emsisson/ondemand/data/sys/myjobs/projects/default/1\"]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mWorkflow Create (29.2ms)\e[0m \e[1m\e[32mINSERT INTO \"workflows\" (\"created_at\", \"updated_at\", \"name\", \"batch_host\", \"staged_dir\", \"script_name\") VALUES (?, ?, ?, ?, ?, ?)\e[0m [[\"created_at\", \"2022-06-22 19:55:02.046215\"], [\"updated_at\", \"2022-06-22 19:55:02.046215\"], [\"name\", \"Test-0622\"], [\"batch_host\", \"smok\"], [\"staged_dir\", \"/home/emsisson/ondemand/data/sys/myjobs/projects/default/1\"], [\"script_name\", \"example.lsf\"]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[35m (10.1ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 14:55:02 -0500 ] INFO "method=POST path=/pun/sys/myjobs/workflows format=html controller=WorkflowsController action=create status=302 duration=82.42 view=0.00 db=39.33 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs/workflows" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (26.7ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[35mSQL (33.7ms)\e[0m \e[1m\e[34mSELECT \"workflows\".\"id\" AS t0_r0, \"workflows\".\"created_at\" AS t0_r1, \"workflows\".\"updated_at\" AS t0_r2, \"workflows\".\"job_attrs\" AS t0_r3, \"workflows\".\"name\" AS t0_r4, \"workflows\".\"batch_host\" AS t0_r5, \"workflows\".\"staged_dir\" AS t0_r6, \"workflows\".\"script_name\" AS t0_r7, \"jobs\".\"id\" AS t1_r0, \"jobs\".\"workflow_id\" AS t1_r1, \"jobs\".\"status\" AS t1_r2, \"jobs\".\"job_cache\" AS t1_r3, \"jobs\".\"created_at\" AS t1_r4, \"jobs\".\"updated_at\" AS t1_r5 FROM \"workflows\" LEFT OUTER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\"\e[0m" App 87810 output: [2022-06-22 14:55:02 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows format=html controller=WorkflowsController action=index status=200 duration=162.61 view=29.78 db=126.76" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (31.5ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (26.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mJob Load (25.7ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mJob Load (23.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 14:55:02 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=150.01 view=17.60 db=106.99" App 87662 output: [2022-06-22 14:55:02 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=24.62 view=0.00" App 87662 output: [2022-06-22 14:55:17 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/edit/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=edit status=200 duration=27.92 view=15.89" App 87662 output: [2022-06-22 14:55:18 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=11.39 view=0.00" App 87662 output: [2022-06-22 14:57:23 -0500 ] INFO "method=PUT path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=json controller=FilesController action=update status=200 duration=11.80 view=0.34" App 87810 output: [2022-06-22 14:57:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 14:57:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:57:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 14:57:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:57:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.7ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:57:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:57:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 14:57:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=138.36 view=6.55 db=126.66" App 87662 output: [2022-06-22 14:57:29 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=10.81 view=0.00" App 87810 output: [2022-06-22 14:57:44 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (31.2ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:57:44 -0500 ] DEBUG "\e[1m\e[36mJob Load (36.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 14:57:44 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bsub\"]" App 87810 output: [2022-06-22 14:58:25 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 14:58:25 -0500 ] DEBUG "\e[1m\e[35m (0.0ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 14:58:25 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"681995\"]" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[36mJob Create (36.8ms)\e[0m \e[1m\e[32mINSERT INTO \"jobs\" (\"workflow_id\", \"status\", \"job_cache\", \"created_at\", \"updated_at\") VALUES (?, ?, ?, ?, ?)\e[0m [[\"workflow_id\", 1], [\"status\", \"C\"], [\"job_cache\", \"{\\\"script\\\":\\\"/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf\\\",\\\"pbsid\\\":\\\"681995\\\",\\\"host\\\":\\\"smok\\\"}\"], [\"created_at\", \"2022-06-22 19:59:05.109973\"], [\"updated_at\", \"2022-06-22 19:59:05.109973\"]]" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[35m (11.9ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 14:59:05 -0500 ] INFO "method=PUT path=/pun/sys/myjobs/workflows/1/submit format=html controller=WorkflowsController action=submit status=302 duration=80495.74 view=0.00 db=116.77 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs/workflows" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.8ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[36mJob Destroy (28.1ms)\e[0m \e[1m\e[31mDELETE FROM \"jobs\" WHERE \"jobs\".\"id\" = ?\e[0m [[\"id\", 1]]" App 87810 output: [2022-06-22 14:59:05 -0500 ] DEBUG "\e[1m\e[35m (11.2ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 14:59:05 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bsub\"]" App 87810 output: [2022-06-22 14:59:45 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 14:59:45 -0500 ] DEBUG "\e[1m\e[35m (0.0ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 14:59:45 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682026\"]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mJob Create (31.7ms)\e[0m \e[1m\e[32mINSERT INTO \"jobs\" (\"workflow_id\", \"status\", \"job_cache\", \"created_at\", \"updated_at\") VALUES (?, ?, ?, ?, ?)\e[0m [[\"workflow_id\", 1], [\"status\", \"C\"], [\"job_cache\", \"{\\\"script\\\":\\\"/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf\\\",\\\"pbsid\\\":\\\"682026\\\",\\\"host\\\":\\\"smok\\\"}\"], [\"created_at\", \"2022-06-22 20:00:25.404538\"], [\"updated_at\", \"2022-06-22 20:00:25.404538\"]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[35m (10.9ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 15:00:25 -0500 ] INFO "method=PUT path=/pun/sys/myjobs/workflows/1/submit format=html controller=WorkflowsController action=submit status=302 duration=80284.95 view=0.00 db=142.19 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs/workflows" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (33.7ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=137.88 view=7.86 db=124.64" App 87662 output: [2022-06-22 15:00:25 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=12.17 view=0.00" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.8ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mJob Load (32.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[36mJob Destroy (28.5ms)\e[0m \e[1m\e[31mDELETE FROM \"jobs\" WHERE \"jobs\".\"id\" = ?\e[0m [[\"id\", 2]]" App 87810 output: [2022-06-22 15:00:25 -0500 ] DEBUG "\e[1m\e[35m (10.4ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 15:00:25 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bsub\"]" App 87810 output: [2022-06-22 15:01:05 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 15:01:05 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 15:01:05 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[36mJob Create (31.1ms)\e[0m \e[1m\e[32mINSERT INTO \"jobs\" (\"workflow_id\", \"status\", \"job_cache\", \"created_at\", \"updated_at\") VALUES (?, ?, ?, ?, ?)\e[0m [[\"workflow_id\", 1], [\"status\", \"Q\"], [\"job_cache\", \"{\\\"script\\\":\\\"/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf\\\",\\\"pbsid\\\":\\\"682027\\\",\\\"host\\\":\\\"smok\\\"}\"], [\"created_at\", \"2022-06-22 20:01:45.819778\"], [\"updated_at\", \"2022-06-22 20:01:45.819778\"]]" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[35m (10.2ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 15:01:45 -0500 ] INFO "method=PUT path=/pun/sys/myjobs/workflows/1/submit format=html controller=WorkflowsController action=submit status=302 duration=80272.31 view=0.00 db=141.39 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs/workflows" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.5ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:01:45 -0500 ] INFO "method=PUT path=/pun/sys/myjobs/workflows/1/submit format=html controller=WorkflowsController action=submit status=302 duration=60.48 view=0.00 db=57.57 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs/workflows" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.2ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:01:45 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:01:45 -0500 ] INFO "method=PUT path=/pun/sys/myjobs/workflows/1/submit format=html controller=WorkflowsController action=submit status=302 duration=58.77 view=0.00 db=56.31 location=https://hpcood-test.mdanderson.edu/pun/sys/myjobs/workflows" App 87810 output: [2022-06-22 15:01:46 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:01:46 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:01:46 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:02:26 -0500 ] DEBUG "\e[1m\e[35mSQL (30.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".\"id\" AS t0_r0, \"workflows\".\"created_at\" AS t0_r1, \"workflows\".\"updated_at\" AS t0_r2, \"workflows\".\"job_attrs\" AS t0_r3, \"workflows\".\"name\" AS t0_r4, \"workflows\".\"batch_host\" AS t0_r5, \"workflows\".\"staged_dir\" AS t0_r6, \"workflows\".\"script_name\" AS t0_r7, \"jobs\".\"id\" AS t1_r0, \"jobs\".\"workflow_id\" AS t1_r1, \"jobs\".\"status\" AS t1_r2, \"jobs\".\"job_cache\" AS t1_r3, \"jobs\".\"created_at\" AS t1_r4, \"jobs\".\"updated_at\" AS t1_r5 FROM \"workflows\" LEFT OUTER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\"\e[0m" App 87810 output: [2022-06-22 15:02:26 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows format=html controller=WorkflowsController action=index status=200 duration=40122.39 view=5.67 db=85.52" App 87810 output: [2022-06-22 15:02:26 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:02:26 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.5ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:02:26 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:03:06 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (36.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:03:06 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:03:06 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:03:06 -0500 ] DEBUG "\e[1m\e[36mJob Load (35.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:03:06 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:03:06 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:03:46 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:03:46 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:03:46 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:03:46 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=80202.68 view=10.15 db=131.58" App 87810 output: [2022-06-22 15:03:46 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (33.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:03:46 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:03:46 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:04:26 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (34.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:04:26 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:04:26 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:04:26 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.7ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:04:26 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:04:26 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:05:06 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:05:06 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:05:06 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:05:06 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=80191.53 view=9.09 db=128.17" App 87810 output: [2022-06-22 15:05:06 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (31.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:05:06 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:05:06 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" [ N 2022-06-22 15:05:25.6041 87642/T4 age/Cor/CoreMain.cpp:1147 ]: Checking whether to disconnect long-running connections for process 87662, application /var/www/ood/apps/sys/dashboard (production) App 87810 output: [2022-06-22 15:05:46 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (36.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:05:46 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:05:46 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:05:46 -0500 ] DEBUG "\e[1m\e[36mJob Load (33.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:05:46 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:05:46 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:06:26 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:06:26 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:06:26 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:06:26 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=80220.52 view=9.49 db=130.89" App 87810 output: [2022-06-22 15:06:26 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (34.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:06:26 -0500 ] DEBUG "\e[1m\e[36mJob Load (32.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:06:26 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:07:07 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (31.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:07:07 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:07:07 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:07:07 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.5ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:07:07 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:07:07 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[36mJob Update (30.3ms)\e[0m \e[1m\e[33mUPDATE \"jobs\" SET \"status\" = ?, \"updated_at\" = ? WHERE \"jobs\".\"id\" = ?\e[0m [[\"status\", \"R\"], [\"updated_at\", \"2022-06-22 20:07:47.089858\"], [\"id\", 3]]" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[35m (11.4ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:07:47 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=80288.94 view=9.86 db=224.49" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:07:47 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:07:47 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"682027\"]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[35m (0.1ms)\e[0m \e[1m\e[36mbegin transaction\e[0m" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Update (32.4ms)\e[0m \e[1m\e[33mUPDATE \"jobs\" SET \"status\" = ?, \"updated_at\" = ? WHERE \"jobs\".\"id\" = ?\e[0m [[\"status\", \"C\"], [\"updated_at\", \"2022-06-22 20:08:27.370701\"], [\"id\", 3]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[35m (11.0ms)\e[0m \e[1m\e[36mcommit transaction\e[0m" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (33.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (32.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=40318.76 view=5.97 db=197.73" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.2ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (34.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.8ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=134.26 view=7.05 db=122.94" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (26.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=122.46 view=6.08 db=112.05" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (25.5ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.7ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (25.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=116.44 view=6.07 db=106.27" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:27 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=125.46 view=6.57 db=114.57" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.5ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=124.19 view=5.97 db=114.25" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (26.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=123.25 view=6.21 db=113.08" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (26.8ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=122.20 view=6.13 db=112.46" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.7ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=131.28 view=7.73 db=119.27" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.2ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (33.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=140.29 view=17.36 db=118.52" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (25.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.7ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=121.73 view=6.09 db=111.31" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=128.78 view=8.08 db=116.13" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:28 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.8ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=127.59 view=5.99 db=117.32" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (31.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=135.29 view=6.01 db=124.61" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.8ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=130.65 view=5.65 db=121.16" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.4ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.7ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=125.70 view=5.81 db=115.53" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.8ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (26.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=131.71 view=7.89 db=119.35" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (25.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.5ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=119.68 view=5.70 db=110.20" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.6ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.8ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=123.79 view=7.40 db=112.03" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (35.2ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (29.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:29 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=133.76 view=5.76 db=123.10" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (28.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.7ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mJob Load (30.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mJob Load (34.2ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=133.76 view=5.92 db=123.10" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (31.3ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (30.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.8ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=133.15 view=5.83 db=117.85" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.1ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (25.9ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mJob Load (26.9ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.6ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:30 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=118.14 view=6.64 db=107.54" App 88609 output: [2022-06-22 15:08:32 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=32.61 view=0.00" App 88609 output: [2022-06-22 15:08:32 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=10.57 view=0.00" App 88609 output: [2022-06-22 15:08:32 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=9.91 view=0.00" App 88609 output: [2022-06-22 15:08:32 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=9.86 view=0.00" App 88609 output: [2022-06-22 15:08:32 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=10.98 view=0.00" App 88609 output: [2022-06-22 15:08:32 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=11.41 view=0.00" App 87810 output: [2022-06-22 15:08:40 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (32.8ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" INNER JOIN \"jobs\" ON \"jobs\".\"workflow_id\" = \"workflows\".\"id\" WHERE \"jobs\".\"status\" IN (?, ?, ?, ?)\e[0m [[\"status\", \"H\"], [\"status\", \"Q\"], [\"status\", \"R\"], [\"status\", \"S\"]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mWorkflow Load (27.4ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mJob Load (28.3ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mCACHE Workflow Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"workflows\".* FROM \"workflows\" WHERE \"workflows\".\"id\" = ? LIMIT ?\e[0m [[\"id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mJob Load (27.1ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ? ORDER BY \"jobs\".\"id\" DESC LIMIT ?\e[0m [[\"workflow_id\", 1], [\"LIMIT\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] DEBUG "\e[1m\e[36mCACHE Job Load (0.0ms)\e[0m \e[1m\e[34mSELECT \"jobs\".* FROM \"jobs\" WHERE \"jobs\".\"workflow_id\" = ?\e[0m [[\"workflow_id\", 1]]" App 87810 output: [2022-06-22 15:08:41 -0500 ] INFO "method=GET path=/pun/sys/myjobs/workflows/1 format=json controller=WorkflowsController action=show status=200 duration=125.72 view=5.72 db=115.65" App 88609 output: [2022-06-22 15:08:41 -0500 ] INFO "method=GET path=/pun/sys/dashboard/files/api/v1/fs/home/emsisson/ondemand/data/sys/myjobs/projects/default/1/example.lsf format=html controller=FilesController action=fs status=200 duration=10.89 view=0.00" App 88609 output: [DEPRECATED] `Bundler.with_clean_env` has been deprecated in favor of `Bundler.with_unbundled_env`. If you instead want the environment before bundler was originally loaded, use `Bundler.with_original_env` (called at /var/www/ood/apps/sys/dashboard/app/apps/ood_app.rb:242) App 88609 output: [2022-06-22 15:08:49 -0500 ] INFO "method=GET path=/pun/sys/dashboard/apps/show/activejobs format=html controller=AppsController action=show status=302 duration=7.61 view=0.00 location=https://hpcood-test.mdanderson.edu/pun/sys/dashboard/activejobs" App 88609 output: [2022-06-22 15:08:49 -0500 ] INFO "method=GET path=/pun/sys/dashboard/activejobs format=html controller=ActiveJobsController action=index status=200 duration=58.80 view=54.41" App 88609 output: [2022-06-22 15:08:49 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-u\", \"emsisson\", \"-a\", \"-w\", \"-W\"]" App 88609 output: [2022-06-22 15:09:29 -0500 ] DEBUG "wrote jobs to stream: 3" App 88609 output: [2022-06-22 15:09:29 -0500 ] INFO "method=GET path=/pun/sys/dashboard/activejobs.json format=json controller=ActiveJobsController action=index status=200 duration=40040.55 view=0.00" App 88609 output: [2022-06-22 15:09:51 -0500 ] INFO "execve = [{\"LSF_BINDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin\", \"LSF_LIBDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/lib\", \"LSF_ENVDIR\"=>\"/rissched/lsf/conf\", \"LSF_SERVERDIR\"=>\"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/etc\"}, \"/rissched/lsf/10.1/linux3.10-glibc2.17-x86_64/bin/bjobs\", \"-a\", \"-w\", \"-W\", \"681995\"]"