https://bpa.vpc.ceicdata.com:8884/automations/work-files?automation_id=CN-RKK-T1-3
source code:200
Archive:  workfile_CN-RKK-T1-3.zip
   creating: CN-RKK-T1-3/
  inflating: CN-RKK-T1-3/CN-RKK-T1-3.json
  inflating: CN-RKK-T1-3/CN-RKK-T1-3_init_log.txt
  inflating: CN-RKK-T1-3/cdp_log.txt
   creating: CN-RKK-T1-3/downloads/
   creating: CN-RKK-T1-3/downloads/CNRKT1311/
   creating: CN-RKK-T1-3/downloads/CNRKT1311/CNRKT131/
    linking: CN-RKK-T1-3/downloads/CNRKT1311/CNRKT131/mapping.json  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
    linking: CN-RKK-T1-3/downloads/CNRKT1311/CNRKT131/parser.py  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  inflating: CN-RKK-T1-3/downloads/CNRKT1311/cdp.js
   creating: CN-RKK-T1-3/downloads/CNRKT1311/downloads/
  inflating: CN-RKK-T1-3/downloads/CNRKT1311/index.txt
 extracting: CN-RKK-T1-3/downloads/CNRKT1311/options.json
 extracting: CN-RKK-T1-3/downloads/CNRKT1311/result.txt
 extracting: CN-RKK-T1-3/downloads/CNRKT1311/summary.txt
   creating: CN-RKK-T1-3/downloads/CNRKT1312/
   creating: CN-RKK-T1-3/downloads/CNRKT1312/CNRKT131/
    linking: CN-RKK-T1-3/downloads/CNRKT1312/CNRKT131/mapping.json  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
    linking: CN-RKK-T1-3/downloads/CNRKT1312/CNRKT131/parser.py  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  inflating: CN-RKK-T1-3/downloads/CNRKT1312/cdp.js
   creating: CN-RKK-T1-3/downloads/CNRKT1312/downloads/
 extracting: CN-RKK-T1-3/downloads/CNRKT1312/index.txt
 extracting: CN-RKK-T1-3/downloads/CNRKT1312/options.json
 extracting: CN-RKK-T1-3/downloads/CNRKT1312/result.txt
  inflating: CN-RKK-T1-3/downloads/CNRKT1312/summary.txt
   creating: CN-RKK-T1-3/downloads/CNRKT1313/
   creating: CN-RKK-T1-3/downloads/CNRKT1313/CNRKT131/
    linking: CN-RKK-T1-3/downloads/CNRKT1313/CNRKT131/mapping.json  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
    linking: CN-RKK-T1-3/downloads/CNRKT1313/CNRKT131/parser.py  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  inflating: CN-RKK-T1-3/downloads/CNRKT1313/cdp.js
   creating: CN-RKK-T1-3/downloads/CNRKT1313/downloads/
 extracting: CN-RKK-T1-3/downloads/CNRKT1313/index.txt
 extracting: CN-RKK-T1-3/downloads/CNRKT1313/options.json
 extracting: CN-RKK-T1-3/downloads/CNRKT1313/result.txt
  inflating: CN-RKK-T1-3/downloads/CNRKT1313/summary.txt
   creating: CN-RKK-T1-3/downloads/CNRKT131D1/
   creating: CN-RKK-T1-3/downloads/CNRKT131D1/CNRKT131/
    linking: CN-RKK-T1-3/downloads/CNRKT131D1/CNRKT131/mapping.json  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
    linking: CN-RKK-T1-3/downloads/CNRKT131D1/CNRKT131/parser.py  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
   creating: CN-RKK-T1-3/downloads/CNRKT131D1/downloads/
  inflating: CN-RKK-T1-3/downloads/CNRKT131D1/downloads/table21.20221020.html
  inflating: CN-RKK-T1-3/downloads/CNRKT131D1/downloads/table21.20221021.html
  inflating: CN-RKK-T1-3/downloads/CNRKT131D1/downloads/table21.20221022.html
  inflating: CN-RKK-T1-3/downloads/CNRKT131D1/downloads/table21.20221023.html
 extracting: CN-RKK-T1-3/downloads/CNRKT131D1/index.txt
 extracting: CN-RKK-T1-3/downloads/CNRKT131D1/options.json
  inflating: CN-RKK-T1-3/downloads/CNRKT131D1/pdp.py
   creating: CN-RKK-T1-3/downloads/CNRKT131D2/
   creating: CN-RKK-T1-3/downloads/CNRKT131D2/CNRKT131/
    linking: CN-RKK-T1-3/downloads/CNRKT131D2/CNRKT131/mapping.json  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
    linking: CN-RKK-T1-3/downloads/CNRKT131D2/CNRKT131/parser.py  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  inflating: CN-RKK-T1-3/downloads/CNRKT131D2/application_run.log
 extracting: CN-RKK-T1-3/downloads/CNRKT131D2/arguments_log.txt
   creating: CN-RKK-T1-3/downloads/CNRKT131D2/downloads/
   creating: CN-RKK-T1-3/downloads/CNRKT131D2/downloads/table53_2022-10-24/
 extracting: CN-RKK-T1-3/downloads/CNRKT131D2/index.txt
  inflating: CN-RKK-T1-3/downloads/CNRKT131D2/log.txt
  inflating: CN-RKK-T1-3/downloads/CNRKT131D2/nch.js
 extracting: CN-RKK-T1-3/downloads/CNRKT131D2/options.json
  inflating: CN-RKK-T1-3/downloads/CNRKT131D2/rds_mysql.log
 extracting: CN-RKK-T1-3/downloads/CNRKT131D2/runlog_errors.log
  inflating: CN-RKK-T1-3/downloads/CNRKT131D2/summary.txt
  inflating: CN-RKK-T1-3/parser_log.txt
   creating: CN-RKK-T1-3/parsers/
   creating: CN-RKK-T1-3/parsers/CNRKT131/
   creating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1311/
    linking: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1311/cdp.js  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/cdp.js
  inflating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1311/downloads  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/downloads
   creating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1312/
    linking: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1312/cdp.js  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1312/cdp.js
  inflating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1312/downloads  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1312/downloads
   creating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1313/
    linking: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1313/cdp.js  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/cdp.js
  inflating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT1313/downloads  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/downloads
   creating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D1/
    linking: CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D1/cdp.js  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D1/cdp.js
  inflating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D1/downloads  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D1/downloads
   creating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D2/
  inflating: CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D2/downloads  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D2/downloads
    linking: CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D2/nch.js  -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D2/nch.js
   creating: CN-RKK-T1-3/parsers/CNRKT131/__pycache__/
  inflating: CN-RKK-T1-3/parsers/CNRKT131/__pycache__/parser.cpython-37.pyc
  inflating: CN-RKK-T1-3/parsers/CNRKT131/mapping.json
  inflating: CN-RKK-T1-3/parsers/CNRKT131/metadata.json
  inflating: CN-RKK-T1-3/parsers/CNRKT131/parser.py
  inflating: CN-RKK-T1-3/series.db
  inflating: CN-RKK-T1-3/summary.json
finishing deferred symbolic links:
  CN-RKK-T1-3/downloads/CNRKT1311/CNRKT131/mapping.json -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
  CN-RKK-T1-3/downloads/CNRKT1311/CNRKT131/parser.py -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  CN-RKK-T1-3/downloads/CNRKT1312/CNRKT131/mapping.json -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
  CN-RKK-T1-3/downloads/CNRKT1312/CNRKT131/parser.py -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  CN-RKK-T1-3/downloads/CNRKT1313/CNRKT131/mapping.json -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
  CN-RKK-T1-3/downloads/CNRKT1313/CNRKT131/parser.py -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  CN-RKK-T1-3/downloads/CNRKT131D1/CNRKT131/mapping.json -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
  CN-RKK-T1-3/downloads/CNRKT131D1/CNRKT131/parser.py -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  CN-RKK-T1-3/downloads/CNRKT131D2/CNRKT131/mapping.json -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/mapping.json
  CN-RKK-T1-3/downloads/CNRKT131D2/CNRKT131/parser.py -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT1311/cdp.js -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/cdp.js
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT1311/downloads -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/downloads
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT1312/cdp.js -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1312/cdp.js
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT1312/downloads -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1312/downloads
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT1313/cdp.js -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/cdp.js
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT1313/downloads -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/downloads
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D1/cdp.js -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D1/cdp.js
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D1/downloads -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D1/downloads
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D2/downloads -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D2/downloads
  CN-RKK-T1-3/parsers/CNRKT131/CNRKT131D2/nch.js -> /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D2/nch.js
extract: 'source_files.zip' is not a valid file
https://bpa.vpc.ceicdata.com:8884/automations/mappings?automation_id=CN-RKK-T1-3&pub_code=CNRKKT13
convert /home/cap/workspace/eloco/temp/CN-RKK-T1-3/Mapping_CN-RKK-T1-3.xls -> /home/cap/workspace/eloco/temp/CN-RKK-T1-3/Mapping_CN-RKK-T1-3.xlsx using filter : Calc Office Open XML
CPY VERSION: 2.4
Starting tool: run
['/proj/bpas/dev/cpy-2.4/bin/cap', 'run', '-cap_names', '-cdp_wdp', '-run_in_parallel', '-additional_arguments', '--keep_index']
[info] [phantom] Starting...
[info] [phantom] Execution timeout set to 7200000ms
[info] [genie] No filter match for table15.html
[info] [genie] No filter match for table1_new.html
[info] [cdp] VERSION: 1.3
[info] [phantom] Running suite: 10 steps
[debug] [phantom] Successfully injected Casper client-side utilities
[info] [phantom] Step handleRequestLists 1/10: done in 48ms.
[info] [phantom] Step resetCurrentArgs 2/10: done in 65ms.
[info] [phantom] Step setCurrentFile 3/10: done in 97ms.
[info] [phantom] Step currentArgsForEach 4/10: done in 119ms.
[info] [phantom] Step setCurrentArg 5/20: done in 137ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 6/20: done in 158ms.
[info] [phantom] Step setCurrentFile 7/20: done in 177ms.
[debug] [genie] Starting download steps for file table15.html
[info] [phantom] Step setDownload 8/20: done in 198ms.
[info] [phantom] Step setCurrentFile 9/21: done in 218ms.
[debug] [phantom] opening url: http://data.fz0752.com/chart/housedeal.shtml, HTTP GET
[debug] [phantom] Navigation requested: url=http://data.fz0752.com/chart/housedeal.shtml, type=Other, willNavigate=true, isMainFrame=true
[info] [phantom] Starting...
[info] [phantom] Execution timeout set to 7200000ms
[info] [genie] No filter match for table26_.html
[debug] [genie] Arguments generator function found
[info] [cdp] VERSION: 1.3
[info] [phantom] Running suite: 11 steps
[info] [phantom] Starting...
[info] [phantom] Execution timeout set to 7200000ms
[info] [genie] No filter match for table32.html
[info] [genie] No filter match for table38.html
[info] [genie] No filter match for table4_new.html
[info] [genie] No filter match for table50.html
[info] [cdp] VERSION: 1.3
[info] [phantom] Running suite: 14 steps
[debug] [phantom] Successfully injected Casper client-side utilities
[info] [phantom] Step handleRequestLists 1/11: done in 30ms.
[debug] [phantom] Successfully injected Casper client-side utilities
[info] [phantom] Step resetCurrentArgs 1/14: done in 28ms.
[info] [phantom] Step resetCurrentArgs 2/11: done in 47ms.
[info] [phantom] Step setCurrentFile 2/14: done in 45ms.
[info] [phantom] Step setCurrentFile 3/11: done in 77ms.
[info] [phantom] Step currentArgsForEach 3/14: done in 67ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 4/11: done in 99ms.
[info] [phantom] Step setCurrentArg 4/26: done in 86ms.
[info] [phantom] Step callFileArgen 5/11: done in 117ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 5/26: done in 107ms.
[debug] [phantom] opening url: http://61.143.241.154/NewFQTJ_more.aspx, HTTP GET
[debug] [phantom] Navigation requested: url=http://61.143.241.154/NewFQTJ_more.aspx, type=Other, willNavigate=true, isMainFrame=true
[info] [phantom] Step setCurrentFile 6/26: done in 126ms.
[debug] [genie] Starting download steps for file table32.html
[info] [phantom] Step setDownload 7/26: done in 147ms.
[2022-10-24 02:01:01,676][copperfield_run][INFO][bpas-dev] Copperfield_run is started for CNRKT131D2!
[2022-10-24 02:01:01,676][copperfield_run][INFO][bpas-dev] Input queue: DMP-WDP-IN
[2022-10-24 02:01:01,677][copperfield_run][INFO][bpas-dev] Output queue: DMP-WDP-OUT
[2022-10-24 02:01:01,677][copperfield_run][INFO][bpas-dev] S3 Bucket: bpa-devsource
[2022-10-24 02:01:01,677][copperfield_run][INFO][bpas-dev] Environment: bpas
[2022-10-24 02:01:01,677][copperfield_run][INFO][bpas-dev] Copperfield version: 2.15.2
[2022-10-24 02:01:01,677][copperfield_run][INFO][bpas-dev] Connecting to aws
[2022-10-24 02:01:01,677][copperfield_run][INFO][bpas-dev] {"sqs-sent": "2022-10-24 02:01:01", "wdp-type": "NCH", "timeout": 9000, "business": "ceic", "asap": 0, "inbox-id": "CN-RKK-T1-3", "args": "CN-RKK-T1-3", "wdp-id": "CNRKT131D2"}
[info] [phantom] Step setCurrentFile 8/27: done in 166ms.
[debug] [phantom] opening url: https://xz.newhouse.fang.com/house/lskd/12193/, HTTP GET
[debug] [phantom] Navigation requested: url=https://xz.newhouse.fang.com/house/lskd/12193/, type=Other, willNavigate=true, isMainFrame=true
[2022-10-24 02:01:01,905][copperfield_run][INFO][bpas-dev] Message was sent
[2022-10-24 02:01:01,906][copperfield_run][INFO][bpas-dev] Receiving output for message with id=6c8dc5ee3171af84c752e7eb633ddf369715308b001eab8328462911d034cafd9544b565298583b798221f081908b761b2380b2226445dff63a6bbcc25095d0c:
[2022-10-24 02:01:01,910][rds_connection][INFO][bpas-dev] Output filename: None
[debug] [phantom] Navigation requested: url=https://www.fz0752.com/chart/housedeal.shtml, type=Other, willNavigate=true, isMainFrame=true

[running]: `table21.20221023.html` re-running times: 1
https://www.njhouse.com.cn/include/everyday/2022/dist20221023.htm
[running]: `table21.20221023.html` status_code: 200 request_time: 1.1s

[running]: `table21.20221020.html` re-running times: 1
https://www.njhouse.com.cn/include/everyday/2022/dist20221020.htm
[running]: `table21.20221020.html` status_code: 200 request_time: 1.1s

[running]: `table21.20221022.html` re-running times: 1
https://www.njhouse.com.cn/include/everyday/2022/dist20221022.htm
[running]: `table21.20221022.html` status_code: 200 request_time: 1.2s

[running]: `table21.20221021.html` re-running times: 1
https://www.njhouse.com.cn/include/everyday/2022/dist20221021.htm
[running]: `table21.20221021.html` status_code: 200 request_time: 1.3s
Extracting archives: /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D1/downloads
[debug] [phantom] url changed to "https://www.fz0752.com/chart/housedeal.shtml"
[info] [phantom]  SKIPPED: https://www.fz0752.com/resource/bootstrap/css/font-awesome-ie7.min.css
[info] [phantom]  SKIPPED: https://www.fz0752.com/resource/bootstrap/css/bootstrap-ie78.css
[info] [phantom]  SKIPPED: https://www.fz0752.com/resource/bootstrap337/css/bootstrap.min.css
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/css/main.css?version=1.0
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/css/form-query.css?version=1.0
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/components/css/responsive.css
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/jygs/css/jygs.css
[debug] [phantom] Navigation requested: url=https://xz.newhouse.fang.com/newblog/, type=Other, willNavigate=true, isMainFrame=true
[2022-10-24 02:01:03,913][rds_connection][INFO][bpas-dev] Output filename: None
[debug] [phantom] url changed to "https://xz.newhouse.fang.com/newblog/"
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/js/My97DatePicker/skin/WdatePicker.css
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/images/logo.gif
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/images/clock.png
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/images/wechat_footer.jpg
[info] [phantom]  SKIPPED: https://www.fz0752.com/fontHtml/images/WAP_footer.png
[2022-10-24 02:01:05,916][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:07,929][rds_connection][INFO][bpas-dev] Output filename: None
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.xpath.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.expath.js client side
[debug] [phantom] Successfully injected Casper client-side utilities
[info] [phantom] Step _step 10/27 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step _step 10/27: done in 7206ms.
[2022-10-24 02:01:09,934][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:11,954][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:13,977][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:15,979][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:17,983][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:19,986][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:21,990][rds_connection][INFO][bpas-dev] Output filename: None
[info] [phantom] wait() finished waiting for 15000ms.
[info] [phantom] Step anonymous 11/27 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step anonymous 11/27: done in 22217ms.
[info] [phantom] Step _step 12/31 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step _step 12/31: done in 22237ms.
[info] [phantom] Step _step 13/31 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step _step 13/31: done in 22255ms.
[2022-10-24 02:01:23,993][rds_connection][INFO][bpas-dev] Output filename: None
[info] [phantom] wait() finished waiting for 250ms.
[info] [phantom] Step _step 14/31 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step _step 14/31: done in 22515ms.
[2022-10-24 02:01:25,997][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:28,000][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:30,016][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:32,019][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:34,032][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:36,035][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:38,039][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:40,055][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:42,058][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:44,063][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:46,068][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:48,072][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:50,075][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:52,078][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:54,082][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:56,087][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:01:58,092][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:00,140][rds_connection][INFO][bpas-dev] Output filename: None
[warning] [phantom] Loading resource failed with status=fail: http://61.143.241.154/NewFQTJ_more.aspx
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.xpath.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.expath.js client side
[info] [phantom] Step _step 7/15: done in 60227ms.
[2022-10-24 02:02:02,143][rds_connection][INFO][bpas-dev] Output filename: None
[info] [phantom] wait() finished waiting for 2000ms.
[info] [phantom] Step anonymous 8/15: done in 62242ms.
[info] [phantom] Step _step 9/15: done in 62260ms.
[2022-10-24 02:02:04,147][rds_connection][INFO][bpas-dev] Output filename: None
[info] [phantom] wait() finished waiting for 2000ms.
[info] [phantom] Step callFileArgenResultFunction 10/15: done in 64282ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 11/15: done in 64303ms.
[info] [phantom] Step manageRetries 12/15: done in 64322ms.
2022-10-23
2022-10-22
2022-10-21
2022-10-20
2022-10-19
[info] [phantom] Step currentArgsForEach 13/15: done in 64344ms.
[info] [phantom] Step setCurrentArg 14/80: done in 64362ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 15/80: done in 64382ms.
[info] [phantom] Step setCurrentFile 16/80: done in 64402ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 17/80: done in 64423ms.
[info] [phantom] Step setCurrentFile 18/81: done in 64442ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 19/81: done in 64463ms.
[info] [phantom] Step _thenBypass 20/82: done in 64482ms.
[debug] [genie] Finished steps for file table26_2022-10-23.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 25/82: done in 64502ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 26/82: done in 64522ms.
[info] [phantom] Step anonymous 27/82: done in 64542ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-23
[info] [phantom] Step manageRetries 28/82: done in 64562ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 29/94: done in 64582ms.
[info] [phantom] Step setCurrentFile 30/94: done in 64602ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 31/94: done in 64623ms.
[info] [phantom] Step setCurrentFile 32/95: done in 64642ms.
[2022-10-24 02:02:06,150][rds_connection][INFO][bpas-dev] Output filename: None
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 33/95: done in 64663ms.
[info] [phantom] Step _thenBypass 34/96: done in 64682ms.
[debug] [genie] Finished steps for file table26_2022-10-23.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 39/96: done in 64702ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 40/96: done in 64722ms.
[info] [phantom] Step anonymous 41/96: done in 64742ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-23
[info] [phantom] Step manageRetries 42/96: done in 64763ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 43/108: done in 64783ms.
[info] [phantom] Step setCurrentFile 44/108: done in 64802ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 45/108: done in 64823ms.
[info] [phantom] Step setCurrentFile 46/109: done in 64842ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 47/109: done in 64863ms.
[info] [phantom] Step _thenBypass 48/110: done in 64882ms.
[debug] [genie] Finished steps for file table26_2022-10-23.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 53/110: done in 64903ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 54/110: done in 64922ms.
[info] [phantom] Step anonymous 55/110: done in 64942ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-23
[info] [phantom] Step manageRetries 56/110: done in 64962ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 57/122: done in 64982ms.
[info] [phantom] Step setCurrentFile 58/122: done in 65002ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 59/122: done in 65023ms.
[info] [phantom] Step setCurrentFile 60/123: done in 65042ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 61/123: done in 65063ms.
[info] [phantom] Step _thenBypass 62/124: done in 65082ms.
[debug] [genie] Finished steps for file table26_2022-10-23.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 67/124: done in 65102ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 68/124: done in 65122ms.
[info] [phantom] Step anonymous 69/124: done in 65142ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-23
[info] [phantom] Step manageRetries 70/124: done in 65163ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 71/136: done in 65182ms.
[info] [phantom] Step setCurrentFile 72/136: done in 65202ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 73/136: done in 65223ms.
[info] [phantom] Step setCurrentFile 74/137: done in 65242ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 75/137: done in 65263ms.
[info] [phantom] Step _thenBypass 76/138: done in 65282ms.
[debug] [genie] Finished steps for file table26_2022-10-23.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 81/138: done in 65303ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 82/138: done in 65322ms.
[info] [phantom] Step anonymous 83/138: done in 65342ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-23
[info] [phantom] Step manageRetries 84/138: done in 65363ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 85/150: done in 65383ms.
[info] [phantom] Step setCurrentFile 86/150: done in 65403ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 87/150: done in 65423ms.
[info] [phantom] Step setCurrentFile 88/151: done in 65443ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 89/151: done in 65463ms.
[info] [phantom] Step _thenBypass 90/152: done in 65483ms.
[debug] [genie] Finished steps for file table26_2022-10-23.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 95/152: done in 65503ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 96/152: done in 65523ms.
[info] [phantom] Step anonymous 97/152: done in 65543ms.
[info] [phantom] Step manageRetries 98/152: done in 65563ms.
[info] [phantom] Step setCurrentArg 99/152: done in 65583ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 100/152: done in 65603ms.
[info] [phantom] Step setCurrentFile 101/152: done in 65623ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 102/152: done in 65643ms.
[info] [phantom] Step setCurrentFile 103/153: done in 65663ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 104/153: done in 65685ms.
[info] [phantom] Step _thenBypass 105/154: done in 65703ms.
[debug] [genie] Finished steps for file table26_2022-10-22.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 110/154: done in 65723ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 111/154: done in 65743ms.
[info] [phantom] Step anonymous 112/154: done in 65763ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-22
[info] [phantom] Step manageRetries 113/154: done in 65783ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 114/166: done in 65803ms.
[info] [phantom] Step setCurrentFile 115/166: done in 65823ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 116/166: done in 65843ms.
[info] [phantom] Step setCurrentFile 117/167: done in 65863ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 118/167: done in 65883ms.
[info] [phantom] Step _thenBypass 119/168: done in 65903ms.
[debug] [genie] Finished steps for file table26_2022-10-22.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 124/168: done in 65923ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 125/168: done in 65943ms.
[info] [phantom] Step anonymous 126/168: done in 65963ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-22
[info] [phantom] Step manageRetries 127/168: done in 65983ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 128/180: done in 66003ms.
[info] [phantom] Step setCurrentFile 129/180: done in 66023ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 130/180: done in 66043ms.
[info] [phantom] Step setCurrentFile 131/181: done in 66063ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 132/181: done in 66083ms.
[info] [phantom] Step _thenBypass 133/182: done in 66103ms.
[debug] [genie] Finished steps for file table26_2022-10-22.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 138/182: done in 66123ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 139/182: done in 66143ms.
[info] [phantom] Step anonymous 140/182: done in 66163ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-22
[info] [phantom] Step manageRetries 141/182: done in 66183ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 142/194: done in 66203ms.
[info] [phantom] Step setCurrentFile 143/194: done in 66223ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 144/194: done in 66243ms.
[info] [phantom] Step setCurrentFile 145/195: done in 66263ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 146/195: done in 66283ms.
[info] [phantom] Step _thenBypass 147/196: done in 66303ms.
[debug] [genie] Finished steps for file table26_2022-10-22.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 152/196: done in 66323ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 153/196: done in 66343ms.
[info] [phantom] Step anonymous 154/196: done in 66363ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-22
[info] [phantom] Step manageRetries 155/196: done in 66383ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 156/208: done in 66404ms.
[info] [phantom] Step setCurrentFile 157/208: done in 66423ms.
(node) warning: possible EventEmitter memory leak detected. %d listeners added. Use emitter.setMaxListeners() to increase limit. 11

[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 158/208: done in 66443ms.
[info] [phantom] Step setCurrentFile 159/209: done in 66463ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 160/209: done in 66484ms.
[info] [phantom] Step _thenBypass 161/210: done in 66503ms.
[debug] [genie] Finished steps for file table26_2022-10-22.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 166/210: done in 66523ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 167/210: done in 66543ms.
[info] [phantom] Step anonymous 168/210: done in 66563ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-22
[info] [phantom] Step manageRetries 169/210: done in 66583ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 170/222: done in 66603ms.
[info] [phantom] Step setCurrentFile 171/222: done in 66623ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 172/222: done in 66643ms.
[2022-10-24 02:02:08,153][rds_connection][INFO][bpas-dev] Output filename: None
[info] [phantom] Step setCurrentFile 173/223: done in 66663ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 174/223: done in 66684ms.
[info] [phantom] Step _thenBypass 175/224: done in 66703ms.
[debug] [genie] Finished steps for file table26_2022-10-22.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 180/224: done in 66723ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 181/224: done in 66743ms.
[info] [phantom] Step anonymous 182/224: done in 66763ms.
[info] [phantom] Step manageRetries 183/224: done in 66783ms.
[info] [phantom] Step setCurrentArg 184/224: done in 66803ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 185/224: done in 66823ms.
[info] [phantom] Step setCurrentFile 186/224: done in 66843ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 187/224: done in 66863ms.
[info] [phantom] Step setCurrentFile 188/225: done in 66883ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 189/225: done in 66904ms.
[info] [phantom] Step _thenBypass 190/226: done in 66923ms.
[debug] [genie] Finished steps for file table26_2022-10-21.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 195/226: done in 66943ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 196/226: done in 66963ms.
[info] [phantom] Step anonymous 197/226: done in 66983ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-21
[info] [phantom] Step manageRetries 198/226: done in 67003ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 199/238: done in 67023ms.
[info] [phantom] Step setCurrentFile 200/238: done in 67043ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 201/238: done in 67063ms.
[info] [phantom] Step setCurrentFile 202/239: done in 67083ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 203/239: done in 67104ms.
[info] [phantom] Step _thenBypass 204/240: done in 67123ms.
[debug] [genie] Finished steps for file table26_2022-10-21.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 209/240: done in 67144ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 210/240: done in 67164ms.
[info] [phantom] Step anonymous 211/240: done in 67184ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-21
[info] [phantom] Step manageRetries 212/240: done in 67210ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 213/252: done in 67230ms.
[info] [phantom] Step setCurrentFile 214/252: done in 67250ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 215/252: done in 67270ms.
[info] [phantom] Step setCurrentFile 216/253: done in 67290ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 217/253: done in 67310ms.
[info] [phantom] Step _thenBypass 218/254: done in 67330ms.
[debug] [genie] Finished steps for file table26_2022-10-21.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 223/254: done in 67352ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 224/254: done in 67370ms.
[info] [phantom] Step anonymous 225/254: done in 67390ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-21
[info] [phantom] Step manageRetries 226/254: done in 67410ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 227/266: done in 67430ms.
[info] [phantom] Step setCurrentFile 228/266: done in 67450ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 229/266: done in 67470ms.
[info] [phantom] Step setCurrentFile 230/267: done in 67490ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 231/267: done in 67511ms.
[info] [phantom] Step _thenBypass 232/268: done in 67530ms.
[debug] [genie] Finished steps for file table26_2022-10-21.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 237/268: done in 67550ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 238/268: done in 67570ms.
[info] [phantom] Step anonymous 239/268: done in 67590ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-21
[info] [phantom] Step manageRetries 240/268: done in 67610ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 241/280: done in 67630ms.
[info] [phantom] Step setCurrentFile 242/280: done in 67650ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 243/280: done in 67670ms.
[info] [phantom] Step setCurrentFile 244/281: done in 67690ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 245/281: done in 67711ms.
[info] [phantom] Step _thenBypass 246/282: done in 67730ms.
[debug] [genie] Finished steps for file table26_2022-10-21.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 251/282: done in 67750ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 252/282: done in 67770ms.
[info] [phantom] Step anonymous 253/282: done in 67790ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-21
[info] [phantom] Step manageRetries 254/282: done in 67811ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 255/294: done in 67831ms.
[info] [phantom] Step setCurrentFile 256/294: done in 67851ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 257/294: done in 67871ms.
[info] [phantom] Step setCurrentFile 258/295: done in 67891ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 259/295: done in 67912ms.
[info] [phantom] Step _thenBypass 260/296: done in 67931ms.
[debug] [genie] Finished steps for file table26_2022-10-21.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 265/296: done in 67951ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 266/296: done in 67971ms.
[info] [phantom] Step anonymous 267/296: done in 67991ms.
[info] [phantom] Step manageRetries 268/296: done in 68011ms.
[info] [phantom] Step setCurrentArg 269/296: done in 68031ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 270/296: done in 68051ms.
[info] [phantom] Step setCurrentFile 271/296: done in 68071ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 272/296: done in 68091ms.
[info] [phantom] Step setCurrentFile 273/297: done in 68111ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 274/297: done in 68131ms.
[info] [phantom] Step _thenBypass 275/298: done in 68151ms.
[debug] [genie] Finished steps for file table26_2022-10-20.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 280/298: done in 68171ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 281/298: done in 68191ms.
[info] [phantom] Step anonymous 282/298: done in 68211ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-20
[info] [phantom] Step manageRetries 283/298: done in 68231ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 284/310: done in 68251ms.
[info] [phantom] Step setCurrentFile 285/310: done in 68271ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 286/310: done in 68291ms.
[info] [phantom] Step setCurrentFile 287/311: done in 68311ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 288/311: done in 68331ms.
[info] [phantom] Step _thenBypass 289/312: done in 68351ms.
[debug] [genie] Finished steps for file table26_2022-10-20.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 294/312: done in 68371ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 295/312: done in 68391ms.
[info] [phantom] Step anonymous 296/312: done in 68411ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-20
[info] [phantom] Step manageRetries 297/312: done in 68431ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 298/324: done in 68451ms.
[info] [phantom] Step setCurrentFile 299/324: done in 68471ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 300/324: done in 68491ms.
[info] [phantom] Step setCurrentFile 301/325: done in 68511ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 302/325: done in 68531ms.
[info] [phantom] Step _thenBypass 303/326: done in 68551ms.
[debug] [genie] Finished steps for file table26_2022-10-20.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 308/326: done in 68571ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 309/326: done in 68591ms.
[info] [phantom] Step anonymous 310/326: done in 68611ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-20
[info] [phantom] Step manageRetries 311/326: done in 68631ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 312/338: done in 68651ms.
[2022-10-24 02:02:10,165][rds_connection][INFO][bpas-dev] Output filename: None
[info] [phantom] Step setCurrentFile 313/338: done in 68671ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 314/338: done in 68691ms.
[info] [phantom] Step setCurrentFile 315/339: done in 68711ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 316/339: done in 68732ms.
[info] [phantom] Step _thenBypass 317/340: done in 68751ms.
[debug] [genie] Finished steps for file table26_2022-10-20.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 322/340: done in 68771ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 323/340: done in 68791ms.
[info] [phantom] Step anonymous 324/340: done in 68811ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-20
[info] [phantom] Step manageRetries 325/340: done in 68831ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 326/352: done in 68851ms.
[info] [phantom] Step setCurrentFile 327/352: done in 68871ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 328/352: done in 68891ms.
[info] [phantom] Step setCurrentFile 329/353: done in 68911ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 330/353: done in 68932ms.
[info] [phantom] Step _thenBypass 331/354: done in 68952ms.
[debug] [genie] Finished steps for file table26_2022-10-20.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 336/354: done in 68972ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 337/354: done in 68992ms.
[info] [phantom] Step anonymous 338/354: done in 69012ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-20
[info] [phantom] Step manageRetries 339/354: done in 69032ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 340/366: done in 69052ms.
[info] [phantom] Step setCurrentFile 341/366: done in 69072ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 342/366: done in 69092ms.
[info] [phantom] Step setCurrentFile 343/367: done in 69111ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 344/367: done in 69134ms.
[info] [phantom] Step _thenBypass 345/368: done in 69152ms.
[debug] [genie] Finished steps for file table26_2022-10-20.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 350/368: done in 69172ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 351/368: done in 69192ms.
[info] [phantom] Step anonymous 352/368: done in 69211ms.
[info] [phantom] Step manageRetries 353/368: done in 69231ms.
[info] [phantom] Step setCurrentArg 354/368: done in 69252ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 355/368: done in 69272ms.
[info] [phantom] Step setCurrentFile 356/368: done in 69292ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 357/368: done in 69312ms.
[info] [phantom] Step setCurrentFile 358/369: done in 69332ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 359/369: done in 69352ms.
[info] [phantom] Step _thenBypass 360/370: done in 69372ms.
[debug] [genie] Finished steps for file table26_2022-10-19.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 365/370: done in 69392ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 366/370: done in 69412ms.
[info] [phantom] Step anonymous 367/370: done in 69432ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-19
[info] [phantom] Step manageRetries 368/370: done in 69452ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 369/382: done in 69472ms.
[info] [phantom] Step setCurrentFile 370/382: done in 69492ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 371/382: done in 69512ms.
[info] [phantom] Step setCurrentFile 372/383: done in 69532ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 373/383: done in 69552ms.
[info] [phantom] Step _thenBypass 374/384: done in 69572ms.
[debug] [genie] Finished steps for file table26_2022-10-19.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 379/384: done in 69592ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 380/384: done in 69612ms.
[info] [phantom] Step anonymous 381/384: done in 69632ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-19
[info] [phantom] Step manageRetries 382/384: done in 69652ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 383/396: done in 69672ms.
[info] [phantom] Step setCurrentFile 384/396: done in 69692ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 385/396: done in 69712ms.
[info] [phantom] Step setCurrentFile 386/397: done in 69732ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 387/397: done in 69752ms.
[info] [phantom] Step _thenBypass 388/398: done in 69772ms.
[debug] [genie] Finished steps for file table26_2022-10-19.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 393/398: done in 69792ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 394/398: done in 69812ms.
[info] [phantom] Step anonymous 395/398: done in 69832ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-19
[info] [phantom] Step manageRetries 396/398: done in 69852ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 397/410: done in 69872ms.
[info] [phantom] Step setCurrentFile 398/410: done in 69892ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 399/410: done in 69912ms.
[info] [phantom] Step setCurrentFile 400/411: done in 69932ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 401/411: done in 69952ms.
[info] [phantom] Step _thenBypass 402/412: done in 69972ms.
[debug] [genie] Finished steps for file table26_2022-10-19.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 407/412: done in 69992ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 408/412: done in 70012ms.
[info] [phantom] Step anonymous 409/412: done in 70032ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-19
[info] [phantom] Step manageRetries 410/412: done in 70052ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 411/424: done in 70072ms.
[info] [phantom] Step setCurrentFile 412/424: done in 70092ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 413/424: done in 70112ms.
[info] [phantom] Step setCurrentFile 414/425: done in 70132ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 415/425: done in 70152ms.
[info] [phantom] Step _thenBypass 416/426: done in 70172ms.
[debug] [genie] Finished steps for file table26_2022-10-19.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 421/426: done in 70192ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 422/426: done in 70212ms.
[info] [phantom] Step anonymous 423/426: done in 70232ms.
[warning] [genie] Retrying steps for file table26_.html because error was found
2022-10-19
[info] [phantom] Step manageRetries 424/426: done in 70252ms.
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 425/438: done in 70272ms.
[info] [phantom] Step setCurrentFile 426/438: done in 70292ms.
[debug] [genie] Starting download steps for file table26_.html
[info] [phantom] Step setDownload 427/438: done in 70312ms.
[info] [phantom] Step setCurrentFile 428/439: done in 70332ms.
[error] [genie] Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found.
[error] [genie] Bypassing 4 steps, due to error!
[info] [phantom] Step anonymous 429/439: done in 70352ms.
[info] [phantom] Step _thenBypass 430/440: done in 70372ms.
[debug] [genie] Finished steps for file table26_2022-10-19.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 435/440: done in 70392ms.
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 436/440: done in 70412ms.
[info] [phantom] Step anonymous 437/440: done in 70432ms.
[info] [phantom] Step manageRetries 438/440: done in 70452ms.
*** Printing SUMMARY ***
{
    "error": {
        "table26_2022-10-23.html": "Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found. ",
        "table26_2022-10-22.html": "Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found. ",
        "table26_2022-10-21.html": "Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found. ",
        "table26_2022-10-20.html": "Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found. ",
        "table26_2022-10-19.html": "Step error: CasperError: Cannot get informations from xpath selector: .//*[@id='input1']: element not found. "
    }
}
*** End printing SUMMARY ***
[info] [phantom] Step anonymous 439/440: done in 70472ms.
[info] [phantom] Step anonymous 440/440: done in 70492ms.
[info] [phantom] Done 440 steps in 70512ms
Extracting archives: /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1312/downloads
[2022-10-24 02:02:12,191][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:14,195][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:16,200][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:18,203][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:20,209][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:22,213][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:24,216][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:26,218][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:28,222][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:30,225][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:32,227][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:34,230][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:36,233][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:38,236][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:40,240][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:42,244][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:44,247][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:46,250][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:48,254][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:50,257][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:52,260][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:54,264][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:56,267][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:02:58,271][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:00,284][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:02,291][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:04,295][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:06,298][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:08,302][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:10,319][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:12,322][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:14,326][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:16,330][rds_connection][INFO][bpas-dev] Output filename: None
[2022-10-24 02:03:18,339][rds_connection][INFO][bpas-dev] All queries are executed!
[2022-10-24 02:03:18,339][rds_connection][INFO][bpas-dev] Output filename: WDP_CNRKT131D2_20221024020102.zip
[2022-10-24 02:03:18,339][copperfield_run][INFO][bpas-dev] Received output for CNRKT131D2 : WDP_CNRKT131D2_20221024020102.zip
[2022-10-24 02:03:18,339][copperfield_run][INFO][bpas-dev] Copying necessary data:
[2022-10-24 02:03:18,339][copperfield_run][INFO][bpas-dev] Filename WDP_CNRKT131D2_20221024020102.zip was found for wdp_id:CNRKT131D2. Looking for the file in copperfield_outputs/.
[2022-10-24 02:03:18,497][copperfield_run][INFO][bpas-dev] copperfield_outputs/CNRKT131D2/WDP_CNRKT131D2_20221024020102.zip was found...
[2022-10-24 02:03:18,569][copperfield_run][INFO][bpas-dev] Setting up working directory for Copperfield...
[2022-10-24 02:03:18,570][copperfield_run][INFO][bpas-dev] Moving index file.
[2022-10-24 02:03:18,570][copperfield_run][INFO][bpas-dev] Moving summary file.
[2022-10-24 02:03:18,571][copperfield_run][INFO][bpas-dev] Moving log file.
[2022-10-24 02:03:18,571][copperfield_run][INFO][bpas-dev] Moving arguments log file.
[2022-10-24 02:03:18,571][copperfield_run][INFO][bpas-dev] Moving application_run.sh log file.
[2022-10-24 02:03:18,571][copperfield_run][INFO][bpas-dev] Moving errors log file.
[2022-10-24 02:03:18,571][copperfield_run][INFO][bpas-dev] Working directory is set up.
Extracting archives: /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D2/downloads
[warning] [phantom] Casper.waitFor() timeout
[error] [genie] Wait timeout : xpath selector: (.//a[contains(translate(normalize-space(@title), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz'), translate(normalize-space('徐州新房成交'), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz')) and contains(translate(normalize-space(@title), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz'), translate(normalize-space('月'), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz'))])[1]
[error] [genie] Bypassing 2 steps, due to error!
[info] [phantom] Step _thenBypass 15/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step _thenBypass 15/32: done in 3622541ms.
[info] [phantom] Step finishDownload 18/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[debug] [genie] Finished steps for file table32.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 18/32: done in 3622562ms.
[info] [phantom] Step enableErrors 19/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 19/32: done in 3622582ms.
[info] [phantom] Step anonymous 20/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step anonymous 20/32: done in 3622601ms.
[info] [phantom] Step manageRetries 21/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step manageRetries 21/32: done in 3622621ms.
[info] [phantom] Step resetCurrentArgs 22/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step resetCurrentArgs 22/32: done in 3622641ms.
[info] [phantom] Step setCurrentFile 23/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step setCurrentFile 23/32: done in 3622661ms.
[info] [phantom] Step currentArgsForEach 24/32 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step currentArgsForEach 24/32: done in 3622682ms.
[info] [phantom] Step setCurrentArg 25/44 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step setCurrentArg 25/44: done in 3622701ms.
[info] [phantom] Step stopErrors 26/44 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 26/44: done in 3622722ms.
[info] [phantom] Step setCurrentFile 27/44 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step setCurrentFile 27/44: done in 3622741ms.
[info] [phantom] Step setDownload 28/44 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[debug] [genie] Starting download steps for file table38.html
[info] [phantom] Step setDownload 28/44: done in 3622762ms.
[info] [phantom] Step setCurrentFile 29/45 https://xz.newhouse.fang.com/newblog/ (HTTP 200)
[info] [phantom] Step setCurrentFile 29/45: done in 3622781ms.
[debug] [phantom] opening url: https://news.efw.cn/chaxun-70.html, HTTP GET
[debug] [phantom] Navigation requested: url=https://news.efw.cn/chaxun-70.html, type=Other, willNavigate=true, isMainFrame=true
[error] [genie] 404 response found (Not Found): https://news.efw.cn/chaxun-70.html
[error] [genie] Bypassing 3 steps, due to error!
[warning] [phantom] Loading resource failed with status=fail (HTTP 404): https://news.efw.cn/chaxun-70.html
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.xpath.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.expath.js client side
[info] [phantom] Step _thenBypass 31/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step _thenBypass 31/46: done in 3624069ms.
[info] [phantom] Step finishDownload 35/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[debug] [genie] Finished steps for file table38.html
[warning] [genie] No download initiated, but error found. Doing nothing
[info] [phantom] Step finishDownload 35/46: done in 3624086ms.
[info] [phantom] Step enableErrors 36/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[debug] [genie] Enabling errors
[info] [phantom] Step enableErrors 36/46: done in 3624106ms.
[info] [phantom] Step anonymous 37/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step anonymous 37/46: done in 3624126ms.
[info] [phantom] Step manageRetries 38/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step manageRetries 38/46: done in 3624146ms.
[info] [phantom] Step resetCurrentArgs 39/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step resetCurrentArgs 39/46: done in 3624166ms.
[info] [phantom] Step setCurrentFile 40/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step setCurrentFile 40/46: done in 3624186ms.
[info] [phantom] Step currentArgsForEach 41/46 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step currentArgsForEach 41/46: done in 3624206ms.
[info] [phantom] Step setCurrentArg 42/58 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step setCurrentArg 42/58: done in 3624226ms.
[info] [phantom] Step stopErrors 43/58 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[debug] [genie] Stopping errors
[info] [phantom] Step stopErrors 43/58: done in 3624246ms.
[info] [phantom] Step setCurrentFile 44/58 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step setCurrentFile 44/58: done in 3624266ms.
[info] [phantom] Step setDownload 45/58 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[debug] [genie] Starting download steps for file table4_new.html
[info] [phantom] Step setDownload 45/58: done in 3624286ms.
[info] [phantom] Step setCurrentFile 46/59 https://xz.newhouse.fang.com/newblog/ (HTTP 404)
[info] [phantom] Step setCurrentFile 46/59: done in 3624306ms.
[debug] [phantom] opening url: http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/, HTTP GET
[debug] [phantom] Navigation requested: url=http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/, type=Other, willNavigate=true, isMainFrame=true
[debug] [phantom] url changed to "http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/"
remote console.log: no valid method for getting user was provided.
remote console.log: sessionCookie expired: undefined, so create new: l9m6zri2_3409_16148
remote console.log: UVCookie expired: undefined, so create new: l9m6zri2_3409_7uv
remote console.log: set UVCookie topLevelDomain: wuhan.gov.cn, _trs_uv=l9m6zri2_3409_7uv
remote console.log: set sessionCookie: _trs_ua_s_1=l9m6zri2_3409_16148
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.xpath.js client side
[debug] [phantom] Automatically injected /proj/bpas/dev/casperjs-1.3/modules/jquery.expath.js client side
[debug] [phantom] Successfully injected Casper client-side utilities
[info] [phantom] Step _step 48/59 http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/ (HTTP 200)
[info] [phantom] Step _step 48/59: done in 3629232ms.
[info] [phantom] wait() finished waiting for 15000ms.
[info] [phantom] Step anonymous 49/59 http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/ (HTTP 200)
[info] [phantom] Step anonymous 49/59: done in 3644235ms.
[info] [phantom] Step _step 50/63 http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/ (HTTP 200)
[info] [phantom] Step _step 50/63: done in 3644256ms.
[info] [phantom] Step _step 51/63 http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/ (HTTP 200)
[info] [phantom] Step _step 51/63: done in 3644274ms.
[info] [phantom] wait() finished waiting for 250ms.
[info] [phantom] Step _step 52/63 http://fgj.wuhan.gov.cn/xxgk/xxgkml/sjfb/mrxjspfcjtjqk/ (HTTP 200)
[info] [phantom] Step _step 52/63: done in 3644534ms.
[error] [phantom] Script timeout of 7200000ms reached, exiting.
Script timeout of 7200000ms reached, exiting.
*** Printing SUMMARY ***
{}
*** End printing SUMMARY ***
Traceback (most recent call last):
  File "/proj/bpas/dev/cdp-1.3/bin/cdp_run", line 290, in <module>
    exec_cmd(get_run_cdp_cmd(CDP_DIR, args.keep_index, params))
  File "/proj/bpas/dev/cdp-1.3/bin/cdp_run", line 118, in exec_cmd
    subprocess.check_call(cmd.split(), stderr=subprocess.STDOUT)
  File "/usr/lib64/python2.7/subprocess.py", line 542, in check_call
    raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['casperjs', '--engine=phantomjs', '--web-security=no', '--ignore-ssl-errors=yes', '--summary_file=/proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/summary.txt', '--ssl-protocol=any', 'cdp.js', '--invocation_options_file=invocation_options.json', '--json_options_file=options.json', '--work_dir=downloads', '--json_options', '--result_file=/proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/result.txt', '--index_file=/proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/index.txt']' returned non-zero exit status 1
Process Process-2:
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/multiprocessing/process.py", line 297, in _bootstrap
    self.run()
  File "/usr/local/lib/python3.7/multiprocessing/process.py", line 99, in run
    self._target(*self._args, **self._kwargs)
  File "/proj/bpas/dev/cpy-2.4/cpy/develop/local/run.py", line 111, in process_dp_parsers
    self.download_process_run(pub_code, cap_name, download_process_name, dp_folder, dp_type, self.run_args)
  File "/proj/bpas/dev/cpy-2.4/cpy/develop/local/run.py", line 215, in download_process_run
    check_call(CDP_RUN + run_args, shell=True, **self.output)
  File "/usr/local/lib/python3.7/subprocess.py", line 363, in check_call
    raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command 'cdp_run --keep_index' returned non-zero exit status 1.
[error] [phantom] Script timeout of 7200000ms reached, exiting.
Script timeout of 7200000ms reached, exiting.
*** Printing SUMMARY ***
{
    "error": {
        "table32.html": "Wait timeout : xpath selector: (.//a[contains(translate(normalize-space(@title), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz'), translate(normalize-space('徐州新房成交'), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz')) and contains(translate(normalize-space(@title), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz'), translate(normalize-space('月'), 'ABCDEFGHIJKLMNOPQRSTUVWXYZ', 'abcdefghijklmnopqrstuvwxyz'))])[1]",
        "table38.html": "404 response found (Not Found): https://news.efw.cn/chaxun-70.html"
    }
}
*** End printing SUMMARY ***
Traceback (most recent call last):
  File "/proj/bpas/dev/cdp-1.3/bin/cdp_run", line 290, in <module>
    exec_cmd(get_run_cdp_cmd(CDP_DIR, args.keep_index, params))
  File "/proj/bpas/dev/cdp-1.3/bin/cdp_run", line 118, in exec_cmd
    subprocess.check_call(cmd.split(), stderr=subprocess.STDOUT)
  File "/usr/lib64/python2.7/subprocess.py", line 542, in check_call
    raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command '['casperjs', '--engine=phantomjs', '--web-security=no', '--ignore-ssl-errors=yes', '--summary_file=/proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/summary.txt', '--ssl-protocol=any', 'cdp.js', '--invocation_options_file=invocation_options.json', '--json_options_file=options.json', '--work_dir=downloads', '--json_options', '--result_file=/proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/result.txt', '--index_file=/proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/index.txt']' returned non-zero exit status 1
Process Process-4:
Traceback (most recent call last):
  File "/usr/local/lib/python3.7/multiprocessing/process.py", line 297, in _bootstrap
    self.run()
  File "/usr/local/lib/python3.7/multiprocessing/process.py", line 99, in run
    self._target(*self._args, **self._kwargs)
  File "/proj/bpas/dev/cpy-2.4/cpy/develop/local/run.py", line 111, in process_dp_parsers
    self.download_process_run(pub_code, cap_name, download_process_name, dp_folder, dp_type, self.run_args)
  File "/proj/bpas/dev/cpy-2.4/cpy/develop/local/run.py", line 215, in download_process_run
    check_call(CDP_RUN + run_args, shell=True, **self.output)
  File "/usr/local/lib/python3.7/subprocess.py", line 363, in check_call
    raise CalledProcessError(retcode, cmd)
subprocess.CalledProcessError: Command 'cdp_run --keep_index' returned non-zero exit status 1.

-------------
CN-RKK-T1-3 {"inbox": "cn-ceic", "download_script_version": 1.3, "processing_script_version": 2.4, "bpas_version": 2.4, "activated_healings": [], "pub_code": "CNRKT131", "parser_interpret": "Python", "parser_lib": null, "conversion_rules": [], "downloads": [{"wdp_type": "CDP", "download_id": "CNRKT1311"}, {"wdp_type": "CDP", "download_id": "CNRKT1312"}, {"wdp_type": "CDP", "download_id": "CNRKT1313"}, {"wdp_type": "PDP", "download_id": "CNRKT131D1"}, {"wdp_type": "NCH", "download_id": "CNRKT131D2"}], "proxy": "NO"}
-------------

############# DOWNLOADING AND FIXING METADATA ##############
Metadata is downloaded: metadata.json
############################################################
CPY VERSION: 2.4
Starting tool: download
['/proj/bpas/dev/cpy-2.4/bin/cap', 'download', '-cap_names', 'CN-RKK-T1-3']
Downloading mapping from BPA
Downloading download mapping from BPA
Downloading download mapping from BPA
Downloading download mapping from BPA
Downloading download mapping from BPA
Downloading download mapping from BPA

-------------
CN-RKK-T1-3 {"inbox": "cn-ceic", "download_script_version": 1.3, "processing_script_version": 2.4, "bpas_version": 2.4, "activated_healings": [], "pub_code": "CNRKT131", "parser_interpret": "Python", "parser_lib": null, "conversion_rules": [], "downloads": [{"wdp_type": "CDP", "download_id": "CNRKT1311"}, {"wdp_type": "CDP", "download_id": "CNRKT1312"}, {"wdp_type": "CDP", "download_id": "CNRKT1313"}, {"wdp_type": "PDP", "download_id": "CNRKT131D1"}, {"wdp_type": "NCH", "download_id": "CNRKT131D2"}], "proxy": "NO"}
-------------

CPY VERSION: 2.4
Starting tool: run
['/proj/bpas/dev/cpy-2.4/bin/cap', 'run', '-cap_names', '-parser']
############# DOWNLOADING AND FIXING METADATA ##############
Metadata is downloaded: metadata.json
############################################################
############# DOWNLOADING AND FIXING METADATA ##############
Metadata is downloaded: metadata.json
############################################################
############# DOWNLOADING AND FIXING METADATA ##############
Metadata is downloaded: metadata.json
############################################################
############# DOWNLOADING AND FIXING METADATA ##############
Metadata is downloaded: metadata.json
############################################################
Starting parser for table21. VERSION: 2.4
Launching table Table21 for table21 sheet1
sheet1 -*- Found 7 rows
sheet1 -*- Saved 3 values
Finished parsing file table21
Starting parser for table21. VERSION: 2.4
Launching table Table21 for table21 sheet1
sheet1 -*- Found 7 rows
sheet1 -*- Saved 3 values
Finished parsing file table21
Starting parser for table21. VERSION: 2.4
Launching table Table21 for table21 sheet1
sheet1 -*- Found 7 rows
sheet1 -*- Saved 3 values
Finished parsing file table21
Starting parser for table21. VERSION: 2.4
Launching table Table21 for table21 sheet1
sheet1 -*- Found 7 rows
sheet1 -*- Saved 3 values
Finished parsing file table21
############# DOWNLOADING AND FIXING METADATA ##############
Metadata is downloaded: metadata.json
############################################################
Downloading mapping from BPA
cpy_run --file /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py --metadata /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/metadata.json /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1311/downloads  --info --append --db --automation_name=CN-RKK-T1-3
Downloading mapping from BPA
cpy_run --file /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py --metadata /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/metadata.json /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1312/downloads  --info --append --db --automation_name=CN-RKK-T1-3
Downloading mapping from BPA
cpy_run --file /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py --metadata /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/metadata.json /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT1313/downloads  --info --append --db --automation_name=CN-RKK-T1-3
Downloading mapping from BPA
cpy_run --file /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py --metadata /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/metadata.json /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D1/downloads  --info --append --db --automation_name=CN-RKK-T1-3
Downloading mapping from BPA
cpy_run --file /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/parser.py --metadata /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/parsers/CNRKT131/metadata.json /proj/bpas/cap_workspace-2.4/CN-RKK-T1-3/downloads/CNRKT131D2/downloads  --info --append --db --automation_name=CN-RKK-T1-3

-------------
CN-RKK-T1-3 {"inbox": "cn-ceic", "download_script_version": 1.3, "processing_script_version": 2.4, "bpas_version": 2.4, "activated_healings": [], "pub_code": "CNRKT131", "parser_interpret": "Python", "parser_lib": null, "conversion_rules": [], "downloads": [{"wdp_type": "CDP", "download_id": "CNRKT1311"}, {"wdp_type": "CDP", "download_id": "CNRKT1312"}, {"wdp_type": "CDP", "download_id": "CNRKT1313"}, {"wdp_type": "PDP", "download_id": "CNRKT131D1"}, {"wdp_type": "NCH", "download_id": "CNRKT131D2"}], "proxy": "NO"}
-------------