2016-09-14 16:51:12 [56717] [INFO] Starting gunicorn 18.0
2016-09-14 16:51:12 [56717] [DEBUG] Arbiter booted
2016-09-14 16:51:12 [56717] [INFO] Listening at: http://0.0.0.0:5000 (56717)
2016-09-14 16:51:12 [56717] [INFO] Using worker: sync
2016-09-14 16:51:12 [56721] [INFO] Booting worker with pid: 56721
2016-09-14 16:51:13,000 /Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/psiturk/psiturk_js/psiturk.js
2016-09-14 16:51:13 [56721] [DEBUG] Closing connection. 
2016-09-14 16:51:13,771 Closing connection. 
2016-09-14 16:51:16 [56721] [DEBUG] POST /launch
2016-09-14 16:51:16,037 POST /launch
>>>> ----- Launching experiment...
2016-09-14 16:51:16,261 Starting new HTTPS connection (1): api.psiturk.org
2016-09-14 16:51:16,517 "GET /api/ad HTTP/1.1" 200 993830
2016-09-14 16:51:20,849 Using access key provided by client.
2016-09-14 16:51:20,849 Using secret key provided by client.
2016-09-14 16:51:20,850 Method: GET
2016-09-14 16:51:20,850 Path: /
2016-09-14 16:51:20,850 Data: 
2016-09-14 16:51:20,850 Headers: {}
2016-09-14 16:51:20,850 Host: rds.amazonaws.com
2016-09-14 16:51:20,850 Port: 443
2016-09-14 16:51:20,850 Params: {'Action': 'DescribeDBInstances', 'Version': '2013-05-15'}
2016-09-14 16:51:20,851 establishing HTTPS connection: host=rds.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-09-14 16:51:20,851 Token: None
2016-09-14 16:51:20,851 CanonicalRequest:
GET
/
Action=DescribeDBInstances&Version=2013-05-15
host:rds.amazonaws.com
x-amz-date:20160914T205120Z

host;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2016-09-14 16:51:20,851 StringToSign:
AWS4-HMAC-SHA256
20160914T205120Z
20160914/us-east-1/rds/aws4_request
29c7233433b980a8da6683166e9d02a055ae0d77ab550af9e51e783c1801a66d
2016-09-14 16:51:20,851 Signature:
93d20cbd360628644518d018f5a9ffc60061f49a3b33c7355be9b76860b918bb
2016-09-14 16:51:20,892 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-09-14 16:51:21,272 validating server certificate: hostname=rds.amazonaws.com, certificate hosts=['rds.us-east-1.amazonaws.com', 'us-east-1.rds.amazonaws.com', 'rds.amazonaws.com']
2016-09-14 16:51:21,329 <DescribeDBInstancesResponse xmlns="http://rds.amazonaws.com/doc/2013-05-15/">
  <DescribeDBInstancesResult>
    <DBInstances/>
  </DescribeDBInstancesResult>
  <ResponseMetadata>
    <RequestId>fddeb51c-7abc-11e6-bb36-91439fb661cc</RequestId>
  </ResponseMetadata>
</DescribeDBInstancesResponse>

2016-09-14 16:51:21,332 Using access key provided by client.
2016-09-14 16:51:21,332 Using secret key provided by client.
2016-09-14 16:51:21,333 Method: POST
2016-09-14 16:51:21,333 Path: /
2016-09-14 16:51:21,333 Data: 
2016-09-14 16:51:21,333 Headers: {}
2016-09-14 16:51:21,333 Host: mechanicalturk.amazonaws.com
2016-09-14 16:51:21,334 Port: 443
2016-09-14 16:51:21,334 Params: {'Operation': 'GetAccountBalance', 'Version': '2012-03-25'}
2016-09-14 16:51:21,334 establishing HTTPS connection: host=mechanicalturk.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-09-14 16:51:21,334 Token: None
2016-09-14 16:51:21,334 using _calc_signature_1
2016-09-14 16:51:21,335 query_string: AWSAccessKeyId=AKIAJ7GUQU3FPLXVOGGQ&Operation=GetAccountBalance&SignatureVersion=1&Timestamp=2016-09-14T20%3A51%3A21Z&Version=2012-03-25 Signature: muM7C478IV3QkgFWAFtXbnhTg6s=
2016-09-14 16:51:21,375 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-09-14 16:51:21,453 validating server certificate: hostname=mechanicalturk.amazonaws.com, certificate hosts=['mechanicalturk.amazonaws.com']
Reject recruitment reopening: experiment has started.
launch request successful.
2016-09-14 16:51:21,730 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:51:21,730 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:51:21] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-09-14 16:51:21,731 127.0.0.1 - - [14/Sep/2016:16:51:21] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-09-14 16:53:59 [56721] [DEBUG] Closing connection. 
2016-09-14 16:53:59,698 Closing connection. 
2016-09-14 16:53:59 [56721] [DEBUG] Closing connection. 
2016-09-14 16:53:59,729 Closing connection. 
2016-09-14 16:54:00 [56721] [DEBUG] GET /ad
2016-09-14 16:54:00,352 GET /ad
127.0.0.1 - - [14/Sep/2016:16:54:00] "GET /ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug HTTP/1.1" 200 3411 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:00,413 127.0.0.1 - - [14/Sep/2016:16:54:00] "GET /ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug HTTP/1.1" 200 3411 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:00 [56721] [DEBUG] GET /static/css/bootstrap.min.css
2016-09-14 16:54:00,802 GET /static/css/bootstrap.min.css
127.0.0.1 - - [14/Sep/2016:16:54:00] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:00,815 127.0.0.1 - - [14/Sep/2016:16:54:00] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:00 [56721] [DEBUG] GET /static/images/logo.png
2016-09-14 16:54:00,816 GET /static/images/logo.png
127.0.0.1 - - [14/Sep/2016:16:54:00] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:00,818 127.0.0.1 - - [14/Sep/2016:16:54:00] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01 [56721] [DEBUG] GET /consent
2016-09-14 16:54:01,484 GET /consent
2016-09-14 16:54:01,492 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:01,492 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug HTTP/1.1" 200 3453 "http://0.0.0.0:5000/ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01,493 127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug HTTP/1.1" 200 3453 "http://0.0.0.0:5000/ad?assignmentId=debugJ05H44&hitId=debug7YK89P&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01 [56721] [DEBUG] GET /static/scripts/reqwest.min.js
2016-09-14 16:54:01,626 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01,630 127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01 [56721] [DEBUG] GET /static/scripts/dallinger.js
2016-09-14 16:54:01,630 GET /static/scripts/dallinger.js
127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /static/scripts/dallinger.js HTTP/1.1" 200 2765 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01,633 127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /static/scripts/dallinger.js HTTP/1.1" 200 2765 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01 [56721] [DEBUG] Closing connection. 
2016-09-14 16:54:01,633 Closing connection. 
2016-09-14 16:54:01 [56721] [DEBUG] GET /static/css/dallinger.css
2016-09-14 16:54:01,634 GET /static/css/dallinger.css
127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /static/css/dallinger.css HTTP/1.1" 200 300 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:01,636 127.0.0.1 - - [14/Sep/2016:16:54:01] "GET /static/css/dallinger.css HTTP/1.1" 200 300 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /instructions/instruct-ready
2016-09-14 16:54:02,389 GET /instructions/instruct-ready
2016-09-14 16:54:02,392 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:02,392 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug HTTP/1.1" 200 2711 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,392 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug HTTP/1.1" 200 2711 "http://0.0.0.0:5000/consent?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&workerId=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/lib/canvasobj.js
2016-09-14 16:54:02,506 GET /static/lib/canvasobj.js
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/canvasobj.js HTTP/1.1" 200 2509 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,508 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/canvasobj.js HTTP/1.1" 200 2509 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/lib/image_preloader.js
2016-09-14 16:54:02,509 GET /static/lib/image_preloader.js
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/image_preloader.js HTTP/1.1" 200 3235 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,511 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/image_preloader.js HTTP/1.1" 200 3235 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/css/canvas.css
2016-09-14 16:54:02,512 GET /static/css/canvas.css
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/css/canvas.css HTTP/1.1" 200 1966 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,514 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/css/canvas.css HTTP/1.1" 200 1966 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/scripts/jquery-min.js
2016-09-14 16:54:02,515 GET /static/scripts/jquery-min.js
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/scripts/jquery-min.js HTTP/1.1" 200 93107 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,517 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/scripts/jquery-min.js HTTP/1.1" 200 93107 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/lib/jquery.url.js
2016-09-14 16:54:02,517 GET /static/lib/jquery.url.js
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/jquery.url.js HTTP/1.1" 200 5056 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,520 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/jquery.url.js HTTP/1.1" 200 5056 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/lib/canvaslib.js
2016-09-14 16:54:02,521 GET /static/lib/canvaslib.js
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/canvaslib.js HTTP/1.1" 200 12519 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,523 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/lib/canvaslib.js HTTP/1.1" 200 12519 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] POST /participant/debugEE8GQV/debug7YK89P/debugJ05H44/debug
2016-09-14 16:54:02,569 POST /participant/debugEE8GQV/debug7YK89P/debugJ05H44/debug
participant post request successful.
2016-09-14 16:54:02,587 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:02,587 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:02] "POST /participant/debugEE8GQV/debug7YK89P/debugJ05H44/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,588 127.0.0.1 - - [14/Sep/2016:16:54:02] "POST /participant/debugEE8GQV/debug7YK89P/debugJ05H44/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/images/delete.png
2016-09-14 16:54:02,595 GET /static/images/delete.png
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/images/delete.png HTTP/1.1" 200 672 "http://0.0.0.0:5000/static/css/canvas.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,598 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/images/delete.png HTTP/1.1" 200 672 "http://0.0.0.0:5000/static/css/canvas.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/images/undo-gray.png
2016-09-14 16:54:02,598 GET /static/images/undo-gray.png
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/images/undo-gray.png HTTP/1.1" 200 545 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,600 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/images/undo-gray.png HTTP/1.1" 200 545 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02 [56721] [DEBUG] GET /static/images/redo-gray.png
2016-09-14 16:54:02,601 GET /static/images/redo-gray.png
127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/images/redo-gray.png HTTP/1.1" 200 549 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:02,604 127.0.0.1 - - [14/Sep/2016:16:54:02] "GET /static/images/redo-gray.png HTTP/1.1" 200 549 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:05 [56721] [DEBUG] GET /static/images/undo.png
2016-09-14 16:54:05,333 GET /static/images/undo.png
127.0.0.1 - - [14/Sep/2016:16:54:05] "GET /static/images/undo.png HTTP/1.1" 200 620 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:05,335 127.0.0.1 - - [14/Sep/2016:16:54:05] "GET /static/images/undo.png HTTP/1.1" 200 620 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08 [56721] [DEBUG] GET /exp
2016-09-14 16:54:08,416 GET /exp
2016-09-14 16:54:08,419 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:08,419 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /exp?participant_id=1 HTTP/1.1" 200 1782 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08,419 127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /exp?participant_id=1 HTTP/1.1" 200 1782 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debug7YK89P&assignment_id=debugJ05H44&worker_id=debugEE8GQV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08 [56721] [DEBUG] GET /static/scripts/markdown.min.js
2016-09-14 16:54:08,530 GET /static/scripts/markdown.min.js
127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /static/scripts/markdown.min.js HTTP/1.1" 200 16798 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08,532 127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /static/scripts/markdown.min.js HTTP/1.1" 200 16798 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08 [56721] [DEBUG] GET /static/scripts/experiment.js
2016-09-14 16:54:08,533 GET /static/scripts/experiment.js
127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /static/scripts/experiment.js HTTP/1.1" 200 2804 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08,535 127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /static/scripts/experiment.js HTTP/1.1" 200 2804 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08 [56721] [DEBUG] POST /node/1
2016-09-14 16:54:08,582 POST /node/1
>>>> 1 10 networks out of 10 available
>>>> 1 No practice networks available.Assigning participant to experiment network 10
/node POST request successful.
2016-09-14 16:54:08,685 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:08,685 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:08] "POST /node/1 HTTP/1.1" 200 278 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08,686 127.0.0.1 - - [14/Sep/2016:16:54:08] "POST /node/1 HTTP/1.1" 200 278 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08 [56721] [DEBUG] GET /node/11/received_infos
2016-09-14 16:54:08,693 GET /node/11/received_infos
received infos request successful.
2016-09-14 16:54:08,705 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:08,705 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /node/11/received_infos HTTP/1.1" 200 4589 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:08,705 127.0.0.1 - - [14/Sep/2016:16:54:08] "GET /node/11/received_infos HTTP/1.1" 200 4589 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] POST /info/11
2016-09-14 16:54:34,377 POST /info/11
info post request successful.
2016-09-14 16:54:34,407 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:34,407 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:34] "POST /info/11 HTTP/1.1" 200 22875 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,407 127.0.0.1 - - [14/Sep/2016:16:54:34] "POST /info/11 HTTP/1.1" 200 22875 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /questionnaire
2016-09-14 16:54:34,423 GET /questionnaire
2016-09-14 16:54:34,426 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:34,426 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /questionnaire?participant_id=1 HTTP/1.1" 200 3223 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,427 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /questionnaire?participant_id=1 HTTP/1.1" 200 3223 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /static/css/bootstrap.min.css
2016-09-14 16:54:34,789 GET /static/css/bootstrap.min.css
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,791 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /static/css/dallinger.css
2016-09-14 16:54:34,792 GET /static/css/dallinger.css
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/css/dallinger.css HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,794 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/css/dallinger.css HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /static/scripts/jquery-min.js
2016-09-14 16:54:34,794 GET /static/scripts/jquery-min.js
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/jquery-min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,796 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/jquery-min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /static/scripts/reqwest.min.js
2016-09-14 16:54:34,797 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,799 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /static/scripts/dallinger.js
2016-09-14 16:54:34,799 GET /static/scripts/dallinger.js
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,801 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34 [56721] [DEBUG] GET /static/scripts/questionnaire.js
2016-09-14 16:54:34,802 GET /static/scripts/questionnaire.js
127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/questionnaire.js HTTP/1.1" 200 1473 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:34,805 127.0.0.1 - - [14/Sep/2016:16:54:34] "GET /static/scripts/questionnaire.js HTTP/1.1" 200 1473 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36 [56721] [DEBUG] POST /question/1
2016-09-14 16:54:36,109 POST /question/1
question post request successful.
2016-09-14 16:54:36,122 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:36,122 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:36] "POST /question/1 HTTP/1.1" 200 21 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36,123 127.0.0.1 - - [14/Sep/2016:16:54:36] "POST /question/1 HTTP/1.1" 200 21 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36 [56721] [DEBUG] POST /question/1
2016-09-14 16:54:36,129 POST /question/1
question post request successful.
2016-09-14 16:54:36,140 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:36,140 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:36] "POST /question/1 HTTP/1.1" 200 21 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36,141 127.0.0.1 - - [14/Sep/2016:16:54:36] "POST /question/1 HTTP/1.1" 200 21 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36 [56721] [DEBUG] GET /participant/1
2016-09-14 16:54:36,146 GET /participant/1
participant get request successful.
2016-09-14 16:54:36,149 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:36,149 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /participant/1 HTTP/1.1" 200 466 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36,149 127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /participant/1 HTTP/1.1" 200 466 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36 [56721] [DEBUG] GET /ad_address/debug/debug7YK89P
2016-09-14 16:54:36,155 GET /ad_address/debug/debug7YK89P
ad_address request successful.
2016-09-14 16:54:36,156 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:54:36,156 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /ad_address/debug/debug7YK89P HTTP/1.1" 200 45 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36,157 127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /ad_address/debug/debug7YK89P HTTP/1.1" 200 45 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36 [56721] [DEBUG] GET /complete
2016-09-14 16:54:36,173 GET /complete
127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /complete?uniqueId=debugEE8GQV:debugJ05H44 HTTP/1.1" 200 630 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36,175 127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /complete?uniqueId=debugEE8GQV:debugJ05H44 HTTP/1.1" 200 630 "http://0.0.0.0:5000/questionnaire?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36 [56721] [DEBUG] GET /static/images/logo.png
2016-09-14 16:54:36,300 GET /static/images/logo.png
127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /static/images/logo.png HTTP/1.1" 304 - "http://0.0.0.0:5000/complete?uniqueId=debugEE8GQV:debugJ05H44" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:54:36,302 127.0.0.1 - - [14/Sep/2016:16:54:36] "GET /static/images/logo.png HTTP/1.1" 304 - "http://0.0.0.0:5000/complete?uniqueId=debugEE8GQV:debugJ05H44" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 16:59:08 [56721] [DEBUG] Closing connection. 
2016-09-14 16:59:08,334 Closing connection. 
2016-09-14 16:59:09 [56721] [DEBUG] Closing connection. 
2016-09-14 16:59:09,136 Closing connection. 
2016-09-14 16:59:09 [56721] [DEBUG] Closing connection. 
2016-09-14 16:59:09,136 Closing connection. 
2016-09-14 16:59:09 [56721] [DEBUG] GET /ppid
2016-09-14 16:59:09,137 GET /ppid
127.0.0.1 - - [14/Sep/2016:16:59:09] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-09-14 16:59:09,138 127.0.0.1 - - [14/Sep/2016:16:59:09] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-09-14 16:59:09 [56721] [DEBUG] Closing connection. 
2016-09-14 16:59:09,169 Closing connection. 
2016-09-14 16:59:09 [56721] [INFO] Parent changed, shutting down: <Worker 56721>
2016-09-14 16:59:09,170 Parent changed, shutting down: <Worker 56721>
2016-09-14 16:59:09 [56721] [INFO] Worker exiting (pid: 56721)
2016-09-14 16:59:09,170 Worker exiting (pid: 56721)
2016-09-14 16:59:52 [57152] [INFO] Starting gunicorn 18.0
2016-09-14 16:59:52 [57152] [DEBUG] Arbiter booted
2016-09-14 16:59:52 [57152] [INFO] Listening at: http://0.0.0.0:5000 (57152)
2016-09-14 16:59:52 [57152] [INFO] Using worker: sync
2016-09-14 16:59:52 [57156] [INFO] Booting worker with pid: 57156
2016-09-14 16:59:53,369 /Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/psiturk/psiturk_js/psiturk.js
2016-09-14 16:59:54 [57156] [DEBUG] Closing connection. 
2016-09-14 16:59:54,479 Closing connection. 
2016-09-14 16:59:56 [57156] [DEBUG] POST /launch
2016-09-14 16:59:56,754 POST /launch
>>>> ----- Launching experiment...
2016-09-14 16:59:56,989 Starting new HTTPS connection (1): api.psiturk.org
2016-09-14 16:59:57,244 "GET /api/ad HTTP/1.1" 200 993830
2016-09-14 16:59:57,620 Using access key provided by client.
2016-09-14 16:59:57,621 Using secret key provided by client.
2016-09-14 16:59:57,621 Method: GET
2016-09-14 16:59:57,621 Path: /
2016-09-14 16:59:57,622 Data: 
2016-09-14 16:59:57,622 Headers: {}
2016-09-14 16:59:57,622 Host: rds.amazonaws.com
2016-09-14 16:59:57,622 Port: 443
2016-09-14 16:59:57,622 Params: {'Action': 'DescribeDBInstances', 'Version': '2013-05-15'}
2016-09-14 16:59:57,622 establishing HTTPS connection: host=rds.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-09-14 16:59:57,623 Token: None
2016-09-14 16:59:57,623 CanonicalRequest:
GET
/
Action=DescribeDBInstances&Version=2013-05-15
host:rds.amazonaws.com
x-amz-date:20160914T205957Z

host;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2016-09-14 16:59:57,623 StringToSign:
AWS4-HMAC-SHA256
20160914T205957Z
20160914/us-east-1/rds/aws4_request
8d714be1374389fbaec9c93def44f625847a0b87851b8149daa628083bde9df1
2016-09-14 16:59:57,624 Signature:
9bf1c174cabb4d1e92ae3fd30d0c26418872af1c7b4040bc41f3c67cec6c2c4f
2016-09-14 16:59:57,660 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-09-14 16:59:57,739 validating server certificate: hostname=rds.amazonaws.com, certificate hosts=['rds.us-east-1.amazonaws.com', 'us-east-1.rds.amazonaws.com', 'rds.amazonaws.com']
2016-09-14 16:59:57,797 <DescribeDBInstancesResponse xmlns="http://rds.amazonaws.com/doc/2013-05-15/">
  <DescribeDBInstancesResult>
    <DBInstances/>
  </DescribeDBInstancesResult>
  <ResponseMetadata>
    <RequestId>31b5c634-7abe-11e6-8860-811ff163b363</RequestId>
  </ResponseMetadata>
</DescribeDBInstancesResponse>

2016-09-14 16:59:57,798 Using access key provided by client.
2016-09-14 16:59:57,798 Using secret key provided by client.
2016-09-14 16:59:57,799 Method: POST
2016-09-14 16:59:57,799 Path: /
2016-09-14 16:59:57,799 Data: 
2016-09-14 16:59:57,799 Headers: {}
2016-09-14 16:59:57,799 Host: mechanicalturk.amazonaws.com
2016-09-14 16:59:57,799 Port: 443
2016-09-14 16:59:57,800 Params: {'Operation': 'GetAccountBalance', 'Version': '2012-03-25'}
2016-09-14 16:59:57,800 establishing HTTPS connection: host=mechanicalturk.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-09-14 16:59:57,800 Token: None
2016-09-14 16:59:57,800 using _calc_signature_1
2016-09-14 16:59:57,801 query_string: AWSAccessKeyId=AKIAJ7GUQU3FPLXVOGGQ&Operation=GetAccountBalance&SignatureVersion=1&Timestamp=2016-09-14T20%3A59%3A57Z&Version=2012-03-25 Signature: HERYRDkFVljd19EnA4byVRIFGo0=
2016-09-14 16:59:58,845 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-09-14 16:59:58,928 validating server certificate: hostname=mechanicalturk.amazonaws.com, certificate hosts=['mechanicalturk.amazonaws.com']
Reject recruitment reopening: experiment has started.
launch request successful.
2016-09-14 16:59:59,224 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 16:59:59,224 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:16:59:59] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-09-14 16:59:59,225 127.0.0.1 - - [14/Sep/2016:16:59:59] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-09-14 17:00:05 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:05,058 Closing connection. 
2016-09-14 17:00:05 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:05,090 Closing connection. 
2016-09-14 17:00:06 [57156] [DEBUG] GET /ad
2016-09-14 17:00:06,022 GET /ad
127.0.0.1 - - [14/Sep/2016:17:00:06] "GET /ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug HTTP/1.1" 200 3411 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:06,052 127.0.0.1 - - [14/Sep/2016:17:00:06] "GET /ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug HTTP/1.1" 200 3411 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:06 [57156] [DEBUG] GET /static/css/bootstrap.min.css
2016-09-14 17:00:06,388 GET /static/css/bootstrap.min.css
127.0.0.1 - - [14/Sep/2016:17:00:06] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:06,399 127.0.0.1 - - [14/Sep/2016:17:00:06] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:06 [57156] [DEBUG] GET /static/images/logo.png
2016-09-14 17:00:06,399 GET /static/images/logo.png
127.0.0.1 - - [14/Sep/2016:17:00:06] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:06,402 127.0.0.1 - - [14/Sep/2016:17:00:06] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07 [57156] [DEBUG] GET /consent
2016-09-14 17:00:07,116 GET /consent
2016-09-14 17:00:07,123 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 17:00:07,123 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug HTTP/1.1" 200 3453 "http://0.0.0.0:5000/ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07,124 127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug HTTP/1.1" 200 3453 "http://0.0.0.0:5000/ad?assignmentId=debugZ2U23L&hitId=debugEA5LIK&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07 [57156] [DEBUG] GET /static/scripts/reqwest.min.js
2016-09-14 17:00:07,246 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07,248 127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07 [57156] [DEBUG] GET /static/scripts/dallinger.js
2016-09-14 17:00:07,249 GET /static/scripts/dallinger.js
127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /static/scripts/dallinger.js HTTP/1.1" 200 2765 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07,251 127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /static/scripts/dallinger.js HTTP/1.1" 200 2765 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:07,253 Closing connection. 
2016-09-14 17:00:07 [57156] [DEBUG] GET /static/css/dallinger.css
2016-09-14 17:00:07,254 GET /static/css/dallinger.css
127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /static/css/dallinger.css HTTP/1.1" 200 300 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07,257 127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /static/css/dallinger.css HTTP/1.1" 200 300 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07 [57156] [DEBUG] GET /instructions/instruct-ready
2016-09-14 17:00:07,931 GET /instructions/instruct-ready
2016-09-14 17:00:07,933 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 17:00:07,933 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug HTTP/1.1" 200 2711 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:07,933 127.0.0.1 - - [14/Sep/2016:17:00:07] "GET /instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug HTTP/1.1" 200 2711 "http://0.0.0.0:5000/consent?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&workerId=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/scripts/jquery-min.js
2016-09-14 17:00:08,043 GET /static/scripts/jquery-min.js
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/scripts/jquery-min.js HTTP/1.1" 200 93107 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,045 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/scripts/jquery-min.js HTTP/1.1" 200 93107 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/lib/canvasobj.js
2016-09-14 17:00:08,046 GET /static/lib/canvasobj.js
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/canvasobj.js HTTP/1.1" 200 2509 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,048 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/canvasobj.js HTTP/1.1" 200 2509 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/lib/image_preloader.js
2016-09-14 17:00:08,049 GET /static/lib/image_preloader.js
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/image_preloader.js HTTP/1.1" 200 3235 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,050 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/image_preloader.js HTTP/1.1" 200 3235 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/css/canvas.css
2016-09-14 17:00:08,051 GET /static/css/canvas.css
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/css/canvas.css HTTP/1.1" 200 1966 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,053 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/css/canvas.css HTTP/1.1" 200 1966 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/lib/jquery.url.js
2016-09-14 17:00:08,053 GET /static/lib/jquery.url.js
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/jquery.url.js HTTP/1.1" 200 5056 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,055 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/jquery.url.js HTTP/1.1" 200 5056 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/lib/canvaslib.js
2016-09-14 17:00:08,056 GET /static/lib/canvaslib.js
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/canvaslib.js HTTP/1.1" 200 12519 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,058 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/lib/canvaslib.js HTTP/1.1" 200 12519 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] POST /participant/debugD8KVG8/debugEA5LIK/debugZ2U23L/debug
2016-09-14 17:00:08,114 POST /participant/debugD8KVG8/debugEA5LIK/debugZ2U23L/debug
participant post request successful.
2016-09-14 17:00:08,129 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 17:00:08,129 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:17:00:08] "POST /participant/debugD8KVG8/debugEA5LIK/debugZ2U23L/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,129 127.0.0.1 - - [14/Sep/2016:17:00:08] "POST /participant/debugD8KVG8/debugEA5LIK/debugZ2U23L/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/images/delete.png
2016-09-14 17:00:08,143 GET /static/images/delete.png
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/images/delete.png HTTP/1.1" 200 672 "http://0.0.0.0:5000/static/css/canvas.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,145 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/images/delete.png HTTP/1.1" 200 672 "http://0.0.0.0:5000/static/css/canvas.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/images/undo-gray.png
2016-09-14 17:00:08,146 GET /static/images/undo-gray.png
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/images/undo-gray.png HTTP/1.1" 200 545 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,148 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/images/undo-gray.png HTTP/1.1" 200 545 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08 [57156] [DEBUG] GET /static/images/redo-gray.png
2016-09-14 17:00:08,149 GET /static/images/redo-gray.png
127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/images/redo-gray.png HTTP/1.1" 200 549 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:08,150 127.0.0.1 - - [14/Sep/2016:17:00:08] "GET /static/images/redo-gray.png HTTP/1.1" 200 549 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:09 [57156] [DEBUG] GET /static/images/undo.png
2016-09-14 17:00:09,586 GET /static/images/undo.png
127.0.0.1 - - [14/Sep/2016:17:00:09] "GET /static/images/undo.png HTTP/1.1" 200 620 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:09,588 127.0.0.1 - - [14/Sep/2016:17:00:09] "GET /static/images/undo.png HTTP/1.1" 200 620 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10 [57156] [DEBUG] GET /exp
2016-09-14 17:00:10,417 GET /exp
2016-09-14 17:00:10,419 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 17:00:10,419 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /exp?participant_id=1 HTTP/1.1" 200 1782 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10,419 127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /exp?participant_id=1 HTTP/1.1" 200 1782 "http://0.0.0.0:5000/instructions/instruct-ready?hit_id=debugEA5LIK&assignment_id=debugZ2U23L&worker_id=debugD8KVG8&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10 [57156] [DEBUG] GET /static/scripts/markdown.min.js
2016-09-14 17:00:10,540 GET /static/scripts/markdown.min.js
127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /static/scripts/markdown.min.js HTTP/1.1" 200 16798 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10,541 127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /static/scripts/markdown.min.js HTTP/1.1" 200 16798 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10 [57156] [DEBUG] GET /static/scripts/experiment.js
2016-09-14 17:00:10,542 GET /static/scripts/experiment.js
127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /static/scripts/experiment.js HTTP/1.1" 200 2804 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10,544 127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /static/scripts/experiment.js HTTP/1.1" 200 2804 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10 [57156] [DEBUG] POST /node/1
2016-09-14 17:00:10,591 POST /node/1
>>>> 1 1 networks out of 1 available
>>>> 1 No practice networks available.Assigning participant to experiment network 1
/node POST request successful.
2016-09-14 17:00:10,703 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 17:00:10,703 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:17:00:10] "POST /node/1 HTTP/1.1" 200 276 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10,704 127.0.0.1 - - [14/Sep/2016:17:00:10] "POST /node/1 HTTP/1.1" 200 276 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10 [57156] [DEBUG] GET /node/2/received_infos
2016-09-14 17:00:10,711 GET /node/2/received_infos
received infos request successful.
2016-09-14 17:00:10,724 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-09-14 17:00:10,724 Closing Dallinger DB session at flask request end
127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /node/2/received_infos HTTP/1.1" 200 4587 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:10,725 127.0.0.1 - - [14/Sep/2016:17:00:10] "GET /node/2/received_infos HTTP/1.1" 200 4587 "http://0.0.0.0:5000/exp?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36"
2016-09-14 17:00:31 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:31,432 Closing connection. 
2016-09-14 17:00:32 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:32,258 Closing connection. 
2016-09-14 17:00:32 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:32,258 Closing connection. 
2016-09-14 17:00:32 [57156] [DEBUG] GET /ppid
2016-09-14 17:00:32,259 GET /ppid
127.0.0.1 - - [14/Sep/2016:17:00:32] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-09-14 17:00:32,260 127.0.0.1 - - [14/Sep/2016:17:00:32] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-09-14 17:00:32 [57156] [DEBUG] Closing connection. 
2016-09-14 17:00:32,293 Closing connection. 
2016-09-14 17:00:32 [57156] [INFO] Parent changed, shutting down: <Worker 57156>
2016-09-14 17:00:32,293 Parent changed, shutting down: <Worker 57156>
2016-09-14 17:00:32 [57156] [INFO] Worker exiting (pid: 57156)
2016-09-14 17:00:32,294 Worker exiting (pid: 57156)
2016-09-14 18:11:38 [60728] [INFO] Starting gunicorn 18.0
2016-09-14 18:11:38 [60728] [DEBUG] Arbiter booted
2016-09-14 18:11:38 [60728] [INFO] Listening at: http://0.0.0.0:5000 (60728)
2016-09-14 18:11:38 [60728] [INFO] Using worker: sync
2016-09-14 18:11:38 [60734] [INFO] Booting worker with pid: 60734
2016-09-14 18:11:38,765 /Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/psiturk/psiturk_js/psiturk.js
2016-09-14 18:11:52 [60734] [INFO] Worker exiting (pid: 60734)
2016-09-14 18:11:52,307 Worker exiting (pid: 60734)
2016-09-14 18:11:52 [60728] [INFO] Handling signal: int
2016-09-14 18:11:52 [60728] [INFO] Shutting down: Master
