2016-11-19 16:38:23 [52971] [INFO] Starting gunicorn 18.0
2016-11-19 16:38:23 [52971] [DEBUG] Arbiter booted
2016-11-19 16:38:23 [52971] [INFO] Listening at: http://0.0.0.0:5000 (52971)
2016-11-19 16:38:23 [52971] [INFO] Using worker: sync
2016-11-19 16:38:23 [52975] [INFO] Booting worker with pid: 52975
2016-11-19 16:38:24,339 /Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/psiturk/psiturk_js/psiturk.js
2016-11-19 16:38:24 [52975] [DEBUG] Closing connection. 
2016-11-19 16:38:24,610 Closing connection. 
2016-11-19 16:38:26 [52975] [DEBUG] POST /launch
2016-11-19 16:38:26,864 POST /launch
>>>> ----- Launching experiment...
2016-11-19 16:38:27,160 Starting new HTTPS connection (1): api.psiturk.org
2016-11-19 16:38:27,353 "GET /api/ad HTTP/1.1" 200 1078375
2016-11-19 16:38:27,695 Using access key provided by client.
2016-11-19 16:38:27,696 Using secret key provided by client.
2016-11-19 16:38:27,696 Method: GET
2016-11-19 16:38:27,696 Path: /
2016-11-19 16:38:27,696 Data: 
2016-11-19 16:38:27,696 Headers: {}
2016-11-19 16:38:27,696 Host: rds.amazonaws.com
2016-11-19 16:38:27,696 Port: 443
2016-11-19 16:38:27,696 Params: {'Action': 'DescribeDBInstances', 'Version': '2013-05-15'}
2016-11-19 16:38:27,697 establishing HTTPS connection: host=rds.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-11-19 16:38:27,697 Token: None
2016-11-19 16:38:27,697 CanonicalRequest:
GET
/
Action=DescribeDBInstances&Version=2013-05-15
host:rds.amazonaws.com
x-amz-date:20161119T213827Z

host;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2016-11-19 16:38:27,697 StringToSign:
AWS4-HMAC-SHA256
20161119T213827Z
20161119/us-east-1/rds/aws4_request
3341104406234dd6a8b7cf642212074c736536d1acb339b576572210e81c0267
2016-11-19 16:38:27,698 Signature:
94ae36cbe5cfa4f75c8d96385ae55cc78e82afc376df7d699df03db2c74eb03a
2016-11-19 16:38:27,734 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-11-19 16:38:27,803 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-11-19 16:38:27,850 <DescribeDBInstancesResponse xmlns="http://rds.amazonaws.com/doc/2013-05-15/">
  <DescribeDBInstancesResult>
    <DBInstances/>
  </DescribeDBInstancesResult>
  <ResponseMetadata>
    <RequestId>81c3779c-aea0-11e6-bd49-33137d567768</RequestId>
  </ResponseMetadata>
</DescribeDBInstancesResponse>

2016-11-19 16:38:27,852 Using access key provided by client.
2016-11-19 16:38:27,852 Using secret key provided by client.
2016-11-19 16:38:27,852 Method: POST
2016-11-19 16:38:27,853 Path: /
2016-11-19 16:38:27,853 Data: 
2016-11-19 16:38:27,853 Headers: {}
2016-11-19 16:38:27,853 Host: mechanicalturk.amazonaws.com
2016-11-19 16:38:27,853 Port: 443
2016-11-19 16:38:27,853 Params: {'Operation': 'GetAccountBalance', 'Version': '2012-03-25'}
2016-11-19 16:38:27,853 establishing HTTPS connection: host=mechanicalturk.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-11-19 16:38:27,854 Token: None
2016-11-19 16:38:27,854 using _calc_signature_1
2016-11-19 16:38:27,854 query_string: AWSAccessKeyId=AKIAJ7GUQU3FPLXVOGGQ&Operation=GetAccountBalance&SignatureVersion=1&Timestamp=2016-11-19T21%3A38%3A27Z&Version=2012-03-25 Signature: DrXyCXUp2mB7TPGBB1Gt1eI5C34=
2016-11-19 16:38:27,884 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-11-19 16:38:28,034 validating server certificate: hostname=mechanicalturk.amazonaws.com, certificate hosts=['mechanicalturk.amazonaws.com']
Reject recruitment reopening: experiment has started.
launch request successful.
2016-11-19 16:38:28,479 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:38:28,479 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:38:28] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-11-19 16:38:28,480 127.0.0.1 - - [19/Nov/2016:16:38:28] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-11-19 16:39:59 [52975] [DEBUG] Closing connection. 
2016-11-19 16:39:59,758 Closing connection. 
2016-11-19 16:39:59 [52975] [DEBUG] Closing connection. 
2016-11-19 16:39:59,817 Closing connection. 
2016-11-19 16:40:01 [52975] [DEBUG] GET /ad
2016-11-19 16:40:01,468 GET /ad
127.0.0.1 - - [19/Nov/2016:16:40:01] "GET /ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:01,509 127.0.0.1 - - [19/Nov/2016:16:40:01] "GET /ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:01 [52975] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 16:40:01,522 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:16:40:01] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:01,540 127.0.0.1 - - [19/Nov/2016:16:40:01] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:01 [52975] [DEBUG] GET /static/images/logo.png
2016-11-19 16:40:01,541 GET /static/images/logo.png
127.0.0.1 - - [19/Nov/2016:16:40:01] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:01,543 127.0.0.1 - - [19/Nov/2016:16:40:01] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:01 [52975] [DEBUG] Closing connection. 
2016-11-19 16:40:01,544 Closing connection. 
2016-11-19 16:40:03 [52975] [DEBUG] GET /consent
2016-11-19 16:40:03,025 GET /consent
2016-11-19 16:40:03,028 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:03,028 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug HTTP/1.1" 200 3365 "http://0.0.0.0:5000/ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03,029 127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug HTTP/1.1" 200 3365 "http://0.0.0.0:5000/ad?assignmentId=debugRY154C&hitId=debugC0BP6G&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03 [52975] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 16:40:03,076 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03,081 127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03 [52975] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 16:40:03,082 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /static/scripts/dallinger.js HTTP/1.1" 200 4281 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03,085 127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /static/scripts/dallinger.js HTTP/1.1" 200 4281 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03 [52975] [DEBUG] GET /static/css/dallinger.css
2016-11-19 16:40:03,087 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /static/css/dallinger.css HTTP/1.1" 200 366 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:03,090 127.0.0.1 - - [19/Nov/2016:16:40:03] "GET /static/css/dallinger.css HTTP/1.1" 200 366 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05 [52975] [DEBUG] GET /instructions/instruct-1
2016-11-19 16:40:05,034 GET /instructions/instruct-1
2016-11-19 16:40:05,037 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:05,037 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:05] "GET /instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05,038 127.0.0.1 - - [19/Nov/2016:16:40:05] "GET /instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&workerId=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05 [52975] [DEBUG] GET /static/images/blue-bar.jpg
2016-11-19 16:40:05,050 GET /static/images/blue-bar.jpg
127.0.0.1 - - [19/Nov/2016:16:40:05] "GET /static/images/blue-bar.jpg HTTP/1.1" 200 10659 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05,052 127.0.0.1 - - [19/Nov/2016:16:40:05] "GET /static/images/blue-bar.jpg HTTP/1.1" 200 10659 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05 [52975] [DEBUG] GET /static/images/red-bar.jpg
2016-11-19 16:40:05,058 GET /static/images/red-bar.jpg
127.0.0.1 - - [19/Nov/2016:16:40:05] "GET /static/images/red-bar.jpg HTTP/1.1" 200 9260 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05,060 127.0.0.1 - - [19/Nov/2016:16:40:05] "GET /static/images/red-bar.jpg HTTP/1.1" 200 9260 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05 [52975] [DEBUG] POST /participant/debugPWZM8F/debugC0BP6G/debugRY154C/debug
2016-11-19 16:40:05,160 POST /participant/debugPWZM8F/debugC0BP6G/debugRY154C/debug
participant post request successful.
2016-11-19 16:40:05,178 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:05,178 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:05] "POST /participant/debugPWZM8F/debugC0BP6G/debugRY154C/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:05,178 127.0.0.1 - - [19/Nov/2016:16:40:05] "POST /participant/debugPWZM8F/debugC0BP6G/debugRY154C/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06 [52975] [DEBUG] GET /instructions/instruct-2
2016-11-19 16:40:06,299 GET /instructions/instruct-2
2016-11-19 16:40:06,302 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:06,302 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /instructions/instruct-2?participant_id=1 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06,303 127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /instructions/instruct-2?participant_id=1 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugC0BP6G&assignment_id=debugRY154C&worker_id=debugPWZM8F&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06 [52975] [DEBUG] GET /static/images/both-bars.jpg
2016-11-19 16:40:06,316 GET /static/images/both-bars.jpg
127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /static/images/both-bars.jpg HTTP/1.1" 200 15788 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06,318 127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /static/images/both-bars.jpg HTTP/1.1" 200 15788 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06 [52975] [DEBUG] GET /static/images/feedback.jpg
2016-11-19 16:40:06,322 GET /static/images/feedback.jpg
127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /static/images/feedback.jpg HTTP/1.1" 200 16334 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06,325 127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /static/images/feedback.jpg HTTP/1.1" 200 16334 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06 [52975] [DEBUG] GET /static/images/adjust.jpg
2016-11-19 16:40:06,332 GET /static/images/adjust.jpg
127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /static/images/adjust.jpg HTTP/1.1" 200 16515 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:06,334 127.0.0.1 - - [19/Nov/2016:16:40:06] "GET /static/images/adjust.jpg HTTP/1.1" 200 16515 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:07 [52975] [DEBUG] GET /instructions/instruct-3
2016-11-19 16:40:07,531 GET /instructions/instruct-3
2016-11-19 16:40:07,534 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:07,534 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:07] "GET /instructions/instruct-3?participant_id=1 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:07,535 127.0.0.1 - - [19/Nov/2016:16:40:07] "GET /instructions/instruct-3?participant_id=1 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:08 [52975] [DEBUG] GET /instructions/instruct-ready
2016-11-19 16:40:08,868 GET /instructions/instruct-ready
2016-11-19 16:40:08,872 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:08,872 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:08] "GET /instructions/instruct-ready?participant_id=1 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:08,872 127.0.0.1 - - [19/Nov/2016:16:40:08] "GET /instructions/instruct-ready?participant_id=1 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /exp
2016-11-19 16:40:10,494 GET /exp
2016-11-19 16:40:10,497 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:10,497 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /exp?participant_id=1 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,498 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /exp?participant_id=1 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /static/scripts/raphael-min.js
2016-11-19 16:40:10,516 GET /static/scripts/raphael-min.js
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/raphael-min.js HTTP/1.1" 200 91386 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,519 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/raphael-min.js HTTP/1.1" 200 91386 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /static/scripts/mousetrap.min.js
2016-11-19 16:40:10,520 GET /static/scripts/mousetrap.min.js
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 200 3839 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,524 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 200 3839 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /static/scripts/mousetrapExtension-pause.min.js
2016-11-19 16:40:10,525 GET /static/scripts/mousetrapExtension-pause.min.js
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 200 174 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,527 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 200 174 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /static/scripts/helpers.js
2016-11-19 16:40:10,528 GET /static/scripts/helpers.js
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/helpers.js HTTP/1.1" 200 1642 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,530 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/helpers.js HTTP/1.1" 200 1642 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /static/scripts/experiment.js
2016-11-19 16:40:10,531 GET /static/scripts/experiment.js
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/experiment.js HTTP/1.1" 200 5462 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,534 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /static/scripts/experiment.js HTTP/1.1" 200 5462 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] POST /node/1
2016-11-19 16:40:10,674 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-11-19 16:40:10,805 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:10,805 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40: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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,806 127.0.0.1 - - [19/Nov/2016:16:40: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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10 [52975] [DEBUG] GET /node/2/received_infos
2016-11-19 16:40:10,832 GET /node/2/received_infos
received infos request successful.
2016-11-19 16:40:10,841 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:10,841 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /node/2/received_infos HTTP/1.1" 200 769 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:10,842 127.0.0.1 - - [19/Nov/2016:16:40:10] "GET /node/2/received_infos HTTP/1.1" 200 769 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] POST /info/2
2016-11-19 16:40:55,638 POST /info/2
info post request successful.
2016-11-19 16:40:55,661 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:55,661 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:55] "POST /info/2 HTTP/1.1" 200 425 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,662 127.0.0.1 - - [19/Nov/2016:16:40:55] "POST /info/2 HTTP/1.1" 200 425 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] POST /node/1
2016-11-19 16:40:55,668 POST /node/1
>>>> 1 No networks available, returning None
2016-11-19 16:40:55,676 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:55,676 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:55] "POST /node/1 HTTP/1.1" 403 19 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,677 127.0.0.1 - - [19/Nov/2016:16:40:55] "POST /node/1 HTTP/1.1" 403 19 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] GET /questionnaire
2016-11-19 16:40:55,692 GET /questionnaire
2016-11-19 16:40:55,695 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:40:55,695 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:40:55] "GET /questionnaire?participant_id=1 HTTP/1.1" 200 3921 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,696 127.0.0.1 - - [19/Nov/2016:16:40:55] "GET /questionnaire?participant_id=1 HTTP/1.1" 200 3921 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 16:40:55,715 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,718 127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] GET /static/css/dallinger.css
2016-11-19 16:40:55,718 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,720 127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 16:40:55,721 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,725 127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55 [52975] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 16:40:55,725 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:40:55,728 127.0.0.1 - - [19/Nov/2016:16:40:55] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] POST /question/1
2016-11-19 16:41:00,293 POST /question/1
question post request successful.
2016-11-19 16:41:00,309 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:00,309 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,309 127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] GET /participant/1
2016-11-19 16:41:00,310 GET /participant/1
participant get request successful.
2016-11-19 16:41:00,314 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:00,314 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,314 127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] POST /question/1
2016-11-19 16:41:00,318 POST /question/1
question post request successful.
2016-11-19 16:41:00,329 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:00,329 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,329 127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] GET /ad_address/debug/debugC0BP6G
2016-11-19 16:41:00,330 GET /ad_address/debug/debugC0BP6G
ad_address request successful.
2016-11-19 16:41:00,331 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:00,331 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:00] "GET /ad_address/debug/debugC0BP6G 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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,331 127.0.0.1 - - [19/Nov/2016:16:41:00] "GET /ad_address/debug/debugC0BP6G 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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] POST /question/1
2016-11-19 16:41:00,338 POST /question/1
question post request successful.
2016-11-19 16:41:00,352 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:00,352 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,354 127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] GET /complete
2016-11-19 16:41:00,354 GET /complete
127.0.0.1 - - [19/Nov/2016:16:41:00] "GET /complete?uniqueId=debugPWZM8F:debugRY154C 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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,357 127.0.0.1 - - [19/Nov/2016:16:41:00] "GET /complete?uniqueId=debugPWZM8F:debugRY154C 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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] POST /question/1
2016-11-19 16:41:00,359 POST /question/1
question post request successful.
2016-11-19 16:41:00,373 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:00,373 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,374 127.0.0.1 - - [19/Nov/2016:16:41:00] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00 [52975] [DEBUG] GET /static/images/logo.png
2016-11-19 16:41:00,374 GET /static/images/logo.png
127.0.0.1 - - [19/Nov/2016:16:41:00] "GET /static/images/logo.png HTTP/1.1" 304 - "http://0.0.0.0:5000/complete?uniqueId=debugPWZM8F:debugRY154C" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:00,377 127.0.0.1 - - [19/Nov/2016:16:41:00] "GET /static/images/logo.png HTTP/1.1" 304 - "http://0.0.0.0:5000/complete?uniqueId=debugPWZM8F:debugRY154C" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:03 [52975] [DEBUG] Closing connection. 
2016-11-19 16:41:03,883 Closing connection. 
2016-11-19 16:41:03 [52975] [DEBUG] Closing connection. 
2016-11-19 16:41:03,918 Closing connection. 
2016-11-19 16:41:04 [52975] [DEBUG] GET /ad
2016-11-19 16:41:04,705 GET /ad
127.0.0.1 - - [19/Nov/2016:16:41:04] "GET /ad?assignmentId=debug66X56W&hitId=debug2Z075W&workerId=debugYNFQKS&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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:04,718 127.0.0.1 - - [19/Nov/2016:16:41:04] "GET /ad?assignmentId=debug66X56W&hitId=debug2Z075W&workerId=debugYNFQKS&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/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05 [52975] [DEBUG] GET /consent
2016-11-19 16:41:05,936 GET /consent
2016-11-19 16:41:05,938 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:05,938 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug HTTP/1.1" 200 3365 "http://0.0.0.0:5000/ad?assignmentId=debug66X56W&hitId=debug2Z075W&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05,940 127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug HTTP/1.1" 200 3365 "http://0.0.0.0:5000/ad?assignmentId=debug66X56W&hitId=debug2Z075W&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05 [52975] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 16:41:05,989 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05,991 127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05 [52975] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 16:41:05,992 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05,994 127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05 [52975] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 16:41:05,995 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05,998 127.0.0.1 - - [19/Nov/2016:16:41:05] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:05 [52975] [DEBUG] GET /static/css/dallinger.css
2016-11-19 16:41:05,999 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:16:41:06] "GET /static/css/dallinger.css HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:06,001 127.0.0.1 - - [19/Nov/2016:16:41:06] "GET /static/css/dallinger.css HTTP/1.1" 304 - "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:06 [52975] [DEBUG] Closing connection. 
2016-11-19 16:41:06,002 Closing connection. 
2016-11-19 16:41:39 [52975] [DEBUG] GET /instructions/instruct-1
2016-11-19 16:41:39,694 GET /instructions/instruct-1
2016-11-19 16:41:39,695 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:39,695 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,696 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&workerId=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39 [52975] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 16:41:39,785 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,787 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39 [52975] [DEBUG] GET /static/css/dallinger.css
2016-11-19 16:41:39,787 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/css/dallinger.css HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,789 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/css/dallinger.css HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39 [52975] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 16:41:39,790 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,791 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39 [52975] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 16:41:39,792 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,794 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39 [52975] [DEBUG] GET /static/images/blue-bar.jpg
2016-11-19 16:41:39,840 GET /static/images/blue-bar.jpg
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/images/blue-bar.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,852 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/images/blue-bar.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39 [52975] [DEBUG] GET /static/images/red-bar.jpg
2016-11-19 16:41:39,854 GET /static/images/red-bar.jpg
127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/images/red-bar.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:39,858 127.0.0.1 - - [19/Nov/2016:16:41:39] "GET /static/images/red-bar.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:40 [52975] [DEBUG] POST /participant/debugYNFQKS/debug2Z075W/debug66X56W/debug
2016-11-19 16:41:40,323 POST /participant/debugYNFQKS/debug2Z075W/debug66X56W/debug
participant post request successful.
2016-11-19 16:41:40,335 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:40,335 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:40] "POST /participant/debugYNFQKS/debug2Z075W/debug66X56W/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:40,336 127.0.0.1 - - [19/Nov/2016:16:41:40] "POST /participant/debugYNFQKS/debug2Z075W/debug66X56W/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41 [52975] [DEBUG] GET /instructions/instruct-2
2016-11-19 16:41:41,444 GET /instructions/instruct-2
2016-11-19 16:41:41,445 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:41,445 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /instructions/instruct-2?participant_id=2 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41,446 127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /instructions/instruct-2?participant_id=2 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debug2Z075W&assignment_id=debug66X56W&worker_id=debugYNFQKS&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41 [52975] [DEBUG] GET /static/images/both-bars.jpg
2016-11-19 16:41:41,461 GET /static/images/both-bars.jpg
127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /static/images/both-bars.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41,463 127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /static/images/both-bars.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41 [52975] [DEBUG] GET /static/images/feedback.jpg
2016-11-19 16:41:41,467 GET /static/images/feedback.jpg
127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /static/images/feedback.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41,469 127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /static/images/feedback.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41 [52975] [DEBUG] GET /static/images/adjust.jpg
2016-11-19 16:41:41,477 GET /static/images/adjust.jpg
127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /static/images/adjust.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:41,479 127.0.0.1 - - [19/Nov/2016:16:41:41] "GET /static/images/adjust.jpg HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:42 [52975] [DEBUG] GET /instructions/instruct-3
2016-11-19 16:41:42,466 GET /instructions/instruct-3
2016-11-19 16:41:42,466 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:42,466 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:42] "GET /instructions/instruct-3?participant_id=2 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:42,467 127.0.0.1 - - [19/Nov/2016:16:41:42] "GET /instructions/instruct-3?participant_id=2 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:43 [52975] [DEBUG] GET /instructions/instruct-ready
2016-11-19 16:41:43,586 GET /instructions/instruct-ready
2016-11-19 16:41:43,587 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:43,587 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:43] "GET /instructions/instruct-ready?participant_id=2 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:43,587 127.0.0.1 - - [19/Nov/2016:16:41:43] "GET /instructions/instruct-ready?participant_id=2 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] GET /exp
2016-11-19 16:41:44,673 GET /exp
2016-11-19 16:41:44,675 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:44,675 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /exp?participant_id=2 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,675 127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /exp?participant_id=2 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] GET /static/scripts/raphael-min.js
2016-11-19 16:41:44,696 GET /static/scripts/raphael-min.js
127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/raphael-min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,698 127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/raphael-min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] GET /static/scripts/mousetrap.min.js
2016-11-19 16:41:44,700 GET /static/scripts/mousetrap.min.js
127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,702 127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] GET /static/scripts/mousetrapExtension-pause.min.js
2016-11-19 16:41:44,703 GET /static/scripts/mousetrapExtension-pause.min.js
127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,705 127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] GET /static/scripts/helpers.js
2016-11-19 16:41:44,707 GET /static/scripts/helpers.js
127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/helpers.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,709 127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/helpers.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] GET /static/scripts/experiment.js
2016-11-19 16:41:44,710 GET /static/scripts/experiment.js
127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/experiment.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,713 127.0.0.1 - - [19/Nov/2016:16:41:44] "GET /static/scripts/experiment.js HTTP/1.1" 304 - "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44 [52975] [DEBUG] POST /node/2
2016-11-19 16:41:44,859 POST /node/2
>>>> 2 1 networks out of 1 available
>>>> 2 No practice networks available.Assigning participant to experiment network 1
/node POST request successful.
2016-11-19 16:41:44,929 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:44,929 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:44] "POST /node/2 HTTP/1.1" 200 276 "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:44,930 127.0.0.1 - - [19/Nov/2016:16:41:44] "POST /node/2 HTTP/1.1" 200 276 "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:45 [52975] [DEBUG] GET /node/3/received_infos
2016-11-19 16:41:45,000 GET /node/3/received_infos
received infos request successful.
2016-11-19 16:41:45,011 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:41:45,011 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:41:45] "GET /node/3/received_infos HTTP/1.1" 200 428 "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:41:45,012 127.0.0.1 - - [19/Nov/2016:16:41:45] "GET /node/3/received_infos HTTP/1.1" 200 428 "http://0.0.0.0:5000/exp?participant_id=2" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:46:08 [52975] [DEBUG] Closing connection. 
2016-11-19 16:46:08,284 Closing connection. 
2016-11-19 16:46:09 [52975] [DEBUG] Closing connection. 
2016-11-19 16:46:09,172 Closing connection. 
2016-11-19 16:46:09 [52975] [DEBUG] Closing connection. 
2016-11-19 16:46:09,173 Closing connection. 
2016-11-19 16:46:09 [52975] [DEBUG] GET /ppid
2016-11-19 16:46:09,176 GET /ppid
127.0.0.1 - - [19/Nov/2016:16:46:09] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-11-19 16:46:09,178 127.0.0.1 - - [19/Nov/2016:16:46:09] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-11-19 16:46:09 [52975] [DEBUG] Closing connection. 
2016-11-19 16:46:09,222 Closing connection. 
2016-11-19 16:46:09 [52975] [INFO] Parent changed, shutting down: <Worker 52975>
2016-11-19 16:46:09,222 Parent changed, shutting down: <Worker 52975>
2016-11-19 16:46:09 [52975] [INFO] Worker exiting (pid: 52975)
2016-11-19 16:46:09,223 Worker exiting (pid: 52975)
2016-11-19 16:51:46 [53758] [INFO] Starting gunicorn 18.0
2016-11-19 16:51:46 [53758] [DEBUG] Arbiter booted
2016-11-19 16:51:46 [53758] [INFO] Listening at: http://0.0.0.0:5000 (53758)
2016-11-19 16:51:46 [53758] [INFO] Using worker: sync
2016-11-19 16:51:46 [53762] [INFO] Booting worker with pid: 53762
2016-11-19 16:51:46,937 /Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/psiturk/psiturk_js/psiturk.js
2016-11-19 16:51:47 [53762] [DEBUG] Closing connection. 
2016-11-19 16:51:47,704 Closing connection. 
2016-11-19 16:51:49 [53762] [DEBUG] POST /launch
2016-11-19 16:51:49,878 POST /launch
>>>> ----- Launching experiment...
2016-11-19 16:51:50,104 Starting new HTTPS connection (1): api.psiturk.org
2016-11-19 16:51:50,428 "GET /api/ad HTTP/1.1" 200 1078375
2016-11-19 16:51:51,021 Using access key provided by client.
2016-11-19 16:51:51,021 Using secret key provided by client.
2016-11-19 16:51:51,021 Method: GET
2016-11-19 16:51:51,022 Path: /
2016-11-19 16:51:51,022 Data: 
2016-11-19 16:51:51,022 Headers: {}
2016-11-19 16:51:51,022 Host: rds.amazonaws.com
2016-11-19 16:51:51,022 Port: 443
2016-11-19 16:51:51,022 Params: {'Action': 'DescribeDBInstances', 'Version': '2013-05-15'}
2016-11-19 16:51:51,022 establishing HTTPS connection: host=rds.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-11-19 16:51:51,022 Token: None
2016-11-19 16:51:51,023 CanonicalRequest:
GET
/
Action=DescribeDBInstances&Version=2013-05-15
host:rds.amazonaws.com
x-amz-date:20161119T215151Z

host;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2016-11-19 16:51:51,023 StringToSign:
AWS4-HMAC-SHA256
20161119T215151Z
20161119/us-east-1/rds/aws4_request
61d6125c20794bd8802767148b56c84f68dd888a7e1794a8fc7f196290f79664
2016-11-19 16:51:51,023 Signature:
617722de466e6a3aa103253fe12551620e2b35030f860d8635094a6558e58c52
2016-11-19 16:51:51,077 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-11-19 16:51:51,161 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-11-19 16:51:51,259 <DescribeDBInstancesResponse xmlns="http://rds.amazonaws.com/doc/2013-05-15/">
  <DescribeDBInstancesResult>
    <DBInstances/>
  </DescribeDBInstancesResult>
  <ResponseMetadata>
    <RequestId>609808b7-aea2-11e6-a56a-13773f47df63</RequestId>
  </ResponseMetadata>
</DescribeDBInstancesResponse>

2016-11-19 16:51:51,266 Using access key provided by client.
2016-11-19 16:51:51,266 Using secret key provided by client.
2016-11-19 16:51:51,267 Method: POST
2016-11-19 16:51:51,271 Path: /
2016-11-19 16:51:51,271 Data: 
2016-11-19 16:51:51,271 Headers: {}
2016-11-19 16:51:51,272 Host: mechanicalturk.amazonaws.com
2016-11-19 16:51:51,272 Port: 443
2016-11-19 16:51:51,272 Params: {'Operation': 'GetAccountBalance', 'Version': '2012-03-25'}
2016-11-19 16:51:51,272 establishing HTTPS connection: host=mechanicalturk.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-11-19 16:51:51,272 Token: None
2016-11-19 16:51:51,273 using _calc_signature_1
2016-11-19 16:51:51,273 query_string: AWSAccessKeyId=AKIAJ7GUQU3FPLXVOGGQ&Operation=GetAccountBalance&SignatureVersion=1&Timestamp=2016-11-19T21%3A51%3A51Z&Version=2012-03-25 Signature: DaXudiLyFgcCUargWSAkjde6Kqw=
2016-11-19 16:51:51,320 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-11-19 16:51:51,448 validating server certificate: hostname=mechanicalturk.amazonaws.com, certificate hosts=['mechanicalturk.amazonaws.com']
Reject recruitment reopening: experiment has started.
launch request successful.
2016-11-19 16:51:52,650 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:51:52,650 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:51:52] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-11-19 16:51:52,651 127.0.0.1 - - [19/Nov/2016:16:51:52] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-11-19 16:52:02 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:02,496 Closing connection. 
2016-11-19 16:52:02 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:02,530 Closing connection. 
2016-11-19 16:52:03 [53762] [DEBUG] GET /ad
2016-11-19 16:52:03,681 GET /ad
127.0.0.1 - - [19/Nov/2016:16:52:03] "GET /ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:03,726 127.0.0.1 - - [19/Nov/2016:16:52:03] "GET /ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:03 [53762] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 16:52:03,737 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:16:52:03] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:03,750 127.0.0.1 - - [19/Nov/2016:16:52:03] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:03 [53762] [DEBUG] GET /static/images/logo.png
2016-11-19 16:52:03,751 GET /static/images/logo.png
127.0.0.1 - - [19/Nov/2016:16:52:03] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:03,755 127.0.0.1 - - [19/Nov/2016:16:52:03] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04 [53762] [DEBUG] GET /consent
2016-11-19 16:52:04,930 GET /consent
2016-11-19 16:52:04,935 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:04,935 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug HTTP/1.1" 200 3474 "http://0.0.0.0:5000/ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04,936 127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug HTTP/1.1" 200 3474 "http://0.0.0.0:5000/ad?assignmentId=debugC3MV8U&hitId=debugJDPILY&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04 [53762] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 16:52:04,986 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04,989 127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04 [53762] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 16:52:04,990 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /static/scripts/dallinger.js HTTP/1.1" 200 4281 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04,992 127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /static/scripts/dallinger.js HTTP/1.1" 200 4281 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:04,992 Closing connection. 
2016-11-19 16:52:04 [53762] [DEBUG] GET /static/css/dallinger.css
2016-11-19 16:52:04,993 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /static/css/dallinger.css HTTP/1.1" 200 366 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:04,995 127.0.0.1 - - [19/Nov/2016:16:52:04] "GET /static/css/dallinger.css HTTP/1.1" 200 366 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06 [53762] [DEBUG] GET /instructions/instruct-1
2016-11-19 16:52:06,049 GET /instructions/instruct-1
2016-11-19 16:52:06,052 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:06,052 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:06] "GET /instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06,053 127.0.0.1 - - [19/Nov/2016:16:52:06] "GET /instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&workerId=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06 [53762] [DEBUG] GET /static/images/blue-bar.jpg
2016-11-19 16:52:06,066 GET /static/images/blue-bar.jpg
127.0.0.1 - - [19/Nov/2016:16:52:06] "GET /static/images/blue-bar.jpg HTTP/1.1" 200 10659 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06,069 127.0.0.1 - - [19/Nov/2016:16:52:06] "GET /static/images/blue-bar.jpg HTTP/1.1" 200 10659 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06 [53762] [DEBUG] GET /static/images/red-bar.jpg
2016-11-19 16:52:06,074 GET /static/images/red-bar.jpg
127.0.0.1 - - [19/Nov/2016:16:52:06] "GET /static/images/red-bar.jpg HTTP/1.1" 200 9260 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06,076 127.0.0.1 - - [19/Nov/2016:16:52:06] "GET /static/images/red-bar.jpg HTTP/1.1" 200 9260 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06 [53762] [DEBUG] POST /participant/debug8AIWFV/debugJDPILY/debugC3MV8U/debug
2016-11-19 16:52:06,202 POST /participant/debug8AIWFV/debugJDPILY/debugC3MV8U/debug
participant post request successful.
2016-11-19 16:52:06,218 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:06,218 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:06] "POST /participant/debug8AIWFV/debugJDPILY/debugC3MV8U/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:06,219 127.0.0.1 - - [19/Nov/2016:16:52:06] "POST /participant/debug8AIWFV/debugJDPILY/debugC3MV8U/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:09 [53762] [DEBUG] GET /instructions/instruct-2
2016-11-19 16:52:09,985 GET /instructions/instruct-2
2016-11-19 16:52:09,988 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:09,988 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:09] "GET /instructions/instruct-2?participant_id=1 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:09,989 127.0.0.1 - - [19/Nov/2016:16:52:09] "GET /instructions/instruct-2?participant_id=1 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugJDPILY&assignment_id=debugC3MV8U&worker_id=debug8AIWFV&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:10 [53762] [DEBUG] GET /static/images/both-bars.jpg
2016-11-19 16:52:10,106 GET /static/images/both-bars.jpg
127.0.0.1 - - [19/Nov/2016:16:52:10] "GET /static/images/both-bars.jpg HTTP/1.1" 200 15788 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:10,108 127.0.0.1 - - [19/Nov/2016:16:52:10] "GET /static/images/both-bars.jpg HTTP/1.1" 200 15788 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:10 [53762] [DEBUG] GET /static/images/feedback.jpg
2016-11-19 16:52:10,112 GET /static/images/feedback.jpg
127.0.0.1 - - [19/Nov/2016:16:52:10] "GET /static/images/feedback.jpg HTTP/1.1" 200 16334 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:10,114 127.0.0.1 - - [19/Nov/2016:16:52:10] "GET /static/images/feedback.jpg HTTP/1.1" 200 16334 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:10 [53762] [DEBUG] GET /static/images/adjust.jpg
2016-11-19 16:52:10,118 GET /static/images/adjust.jpg
127.0.0.1 - - [19/Nov/2016:16:52:10] "GET /static/images/adjust.jpg HTTP/1.1" 200 16515 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:10,120 127.0.0.1 - - [19/Nov/2016:16:52:10] "GET /static/images/adjust.jpg HTTP/1.1" 200 16515 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:12 [53762] [DEBUG] GET /instructions/instruct-3
2016-11-19 16:52:12,474 GET /instructions/instruct-3
2016-11-19 16:52:12,477 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:12,477 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:12] "GET /instructions/instruct-3?participant_id=1 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:12,478 127.0.0.1 - - [19/Nov/2016:16:52:12] "GET /instructions/instruct-3?participant_id=1 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:13 [53762] [DEBUG] GET /instructions/instruct-ready
2016-11-19 16:52:13,629 GET /instructions/instruct-ready
2016-11-19 16:52:13,632 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:13,632 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:13] "GET /instructions/instruct-ready?participant_id=1 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:13,633 127.0.0.1 - - [19/Nov/2016:16:52:13] "GET /instructions/instruct-ready?participant_id=1 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:13 [53762] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 16:52:13,649 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:16:52:13] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:13,651 127.0.0.1 - - [19/Nov/2016:16:52:13] "GET /static/css/bootstrap.min.css HTTP/1.1" 304 - "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /exp
2016-11-19 16:52:14,939 GET /exp
2016-11-19 16:52:14,942 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:14,942 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /exp?participant_id=1 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,943 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /exp?participant_id=1 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 16:52:14,962 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,964 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/reqwest.min.js HTTP/1.1" 304 - "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/css/dallinger.css
2016-11-19 16:52:14,969 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/css/dallinger.css HTTP/1.1" 304 - "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,971 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/css/dallinger.css HTTP/1.1" 304 - "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 16:52:14,971 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,973 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/dallinger.js HTTP/1.1" 304 - "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/raphael-min.js
2016-11-19 16:52:14,975 GET /static/scripts/raphael-min.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/raphael-min.js HTTP/1.1" 200 91386 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,979 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/raphael-min.js HTTP/1.1" 200 91386 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/mousetrap.min.js
2016-11-19 16:52:14,979 GET /static/scripts/mousetrap.min.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 200 3839 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,982 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 200 3839 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/mousetrapExtension-pause.min.js
2016-11-19 16:52:14,982 GET /static/scripts/mousetrapExtension-pause.min.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 200 174 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,985 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 200 174 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/helpers.js
2016-11-19 16:52:14,986 GET /static/scripts/helpers.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/helpers.js HTTP/1.1" 200 1642 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,988 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/helpers.js HTTP/1.1" 200 1642 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14 [53762] [DEBUG] GET /static/scripts/experiment.js
2016-11-19 16:52:14,989 GET /static/scripts/experiment.js
127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/experiment.js HTTP/1.1" 200 5462 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:14,993 127.0.0.1 - - [19/Nov/2016:16:52:14] "GET /static/scripts/experiment.js HTTP/1.1" 200 5462 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:15 [53762] [DEBUG] POST /node/1
2016-11-19 16:52:15,132 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-11-19 16:52:15,253 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:15,253 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:15] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:15,254 127.0.0.1 - - [19/Nov/2016:16:52:15] "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:15 [53762] [DEBUG] GET /node/2/received_infos
2016-11-19 16:52:15,282 GET /node/2/received_infos
received infos request successful.
2016-11-19 16:52:15,294 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 16:52:15,294 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:16:52:15] "GET /node/2/received_infos HTTP/1.1" 200 775 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:15,295 127.0.0.1 - - [19/Nov/2016:16:52:15] "GET /node/2/received_infos HTTP/1.1" 200 775 "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/54.0.2840.98 Safari/537.36"
2016-11-19 16:52:30 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:30,608 Closing connection. 
2016-11-19 16:52:31 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:31,119 Closing connection. 
2016-11-19 16:52:31 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:31,120 Closing connection. 
2016-11-19 16:52:31 [53762] [DEBUG] GET /ppid
2016-11-19 16:52:31,121 GET /ppid
127.0.0.1 - - [19/Nov/2016:16:52:31] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-11-19 16:52:31,122 127.0.0.1 - - [19/Nov/2016:16:52:31] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-11-19 16:52:31 [53762] [DEBUG] Closing connection. 
2016-11-19 16:52:31,158 Closing connection. 
2016-11-19 16:52:31 [53762] [INFO] Parent changed, shutting down: <Worker 53762>
2016-11-19 16:52:31,159 Parent changed, shutting down: <Worker 53762>
2016-11-19 16:52:31 [53762] [INFO] Worker exiting (pid: 53762)
2016-11-19 16:52:31,159 Worker exiting (pid: 53762)
2016-11-19 18:00:12 [58476] [INFO] Starting gunicorn 18.0
2016-11-19 18:00:12 [58476] [DEBUG] Arbiter booted
2016-11-19 18:00:12 [58476] [INFO] Listening at: http://0.0.0.0:5000 (58476)
2016-11-19 18:00:12 [58476] [INFO] Using worker: sync
2016-11-19 18:00:12 [58480] [INFO] Booting worker with pid: 58480
2016-11-19 18:00:13,710 /Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/psiturk/psiturk_js/psiturk.js
2016-11-19 18:00:14 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:14,087 Closing connection. 
2016-11-19 18:00:16 [58480] [DEBUG] POST /launch
2016-11-19 18:00:16,364 POST /launch
>>>> ----- Launching experiment...
2016-11-19 18:00:16,561 Starting new HTTPS connection (1): api.psiturk.org
2016-11-19 18:00:16,784 "GET /api/ad HTTP/1.1" 200 1083637
2016-11-19 18:00:17,276 Using access key provided by client.
2016-11-19 18:00:17,276 Using secret key provided by client.
2016-11-19 18:00:17,276 Method: GET
2016-11-19 18:00:17,277 Path: /
2016-11-19 18:00:17,277 Data: 
2016-11-19 18:00:17,277 Headers: {}
2016-11-19 18:00:17,277 Host: rds.amazonaws.com
2016-11-19 18:00:17,277 Port: 443
2016-11-19 18:00:17,277 Params: {'Action': 'DescribeDBInstances', 'Version': '2013-05-15'}
2016-11-19 18:00:17,277 establishing HTTPS connection: host=rds.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-11-19 18:00:17,277 Token: None
2016-11-19 18:00:17,278 CanonicalRequest:
GET
/
Action=DescribeDBInstances&Version=2013-05-15
host:rds.amazonaws.com
x-amz-date:20161119T230017Z

host;x-amz-date
e3b0c44298fc1c149afbf4c8996fb92427ae41e4649b934ca495991b7852b855
2016-11-19 18:00:17,278 StringToSign:
AWS4-HMAC-SHA256
20161119T230017Z
20161119/us-east-1/rds/aws4_request
6449dff493123e74aaaaf420f733f793f8e1ee4205acc669152c5159036224f7
2016-11-19 18:00:17,278 Signature:
4af6cbc81d86a6fa0659fa73f9bd3511f6ee783f419ede54f8f7f1c1558df90b
2016-11-19 18:00:17,308 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-11-19 18:00:17,375 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-11-19 18:00:17,497 <DescribeDBInstancesResponse xmlns="http://rds.amazonaws.com/doc/2013-05-15/">
  <DescribeDBInstancesResult>
    <DBInstances/>
  </DescribeDBInstancesResult>
  <ResponseMetadata>
    <RequestId>f022294d-aeab-11e6-b13b-d311f0a486fd</RequestId>
  </ResponseMetadata>
</DescribeDBInstancesResponse>

2016-11-19 18:00:17,498 Using access key provided by client.
2016-11-19 18:00:17,498 Using secret key provided by client.
2016-11-19 18:00:17,499 Method: POST
2016-11-19 18:00:17,499 Path: /
2016-11-19 18:00:17,499 Data: 
2016-11-19 18:00:17,499 Headers: {}
2016-11-19 18:00:17,499 Host: mechanicalturk.amazonaws.com
2016-11-19 18:00:17,499 Port: 443
2016-11-19 18:00:17,500 Params: {'Operation': 'GetAccountBalance', 'Version': '2012-03-25'}
2016-11-19 18:00:17,500 establishing HTTPS connection: host=mechanicalturk.amazonaws.com, kwargs={'port': 443, 'timeout': 70}
2016-11-19 18:00:17,500 Token: None
2016-11-19 18:00:17,500 using _calc_signature_1
2016-11-19 18:00:17,500 query_string: AWSAccessKeyId=AKIAJ7GUQU3FPLXVOGGQ&Operation=GetAccountBalance&SignatureVersion=1&Timestamp=2016-11-19T23%3A00%3A17Z&Version=2012-03-25 Signature: Gj7WD18j4odrpp4pGxTgGGa0yHw=
2016-11-19 18:00:17,531 wrapping ssl socket; CA certificate file=/Users/jordansuchow/.virtualenvs/dallinger/lib/python2.7/site-packages/boto/cacerts/cacerts.txt
2016-11-19 18:00:17,602 validating server certificate: hostname=mechanicalturk.amazonaws.com, certificate hosts=['mechanicalturk.amazonaws.com']
Reject recruitment reopening: experiment has started.
launch request successful.
2016-11-19 18:00:17,839 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:17,839 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:17] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-11-19 18:00:17,840 127.0.0.1 - - [19/Nov/2016:18:00:17] "POST /launch HTTP/1.1" 200 21 "-" "curl/7.43.0"
2016-11-19 18:00:19 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:19,412 Closing connection. 
2016-11-19 18:00:19 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:19,447 Closing connection. 
2016-11-19 18:00:20 [58480] [DEBUG] GET /ad
2016-11-19 18:00:20,650 GET /ad
127.0.0.1 - - [19/Nov/2016:18:00:20] "GET /ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:20,693 127.0.0.1 - - [19/Nov/2016:18:00:20] "GET /ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:20 [58480] [DEBUG] GET /static/css/bootstrap.min.css
2016-11-19 18:00:20,723 GET /static/css/bootstrap.min.css
127.0.0.1 - - [19/Nov/2016:18:00:20] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:20,739 127.0.0.1 - - [19/Nov/2016:18:00:20] "GET /static/css/bootstrap.min.css HTTP/1.1" 200 99961 "http://0.0.0.0:5000/ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:20 [58480] [DEBUG] GET /static/images/logo.png
2016-11-19 18:00:20,741 GET /static/images/logo.png
127.0.0.1 - - [19/Nov/2016:18:00:20] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:20,745 127.0.0.1 - - [19/Nov/2016:18:00:20] "GET /static/images/logo.png HTTP/1.1" 200 1005 "http://0.0.0.0:5000/ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21 [58480] [DEBUG] GET /consent
2016-11-19 18:00:21,843 GET /consent
2016-11-19 18:00:21,848 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:21,848 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug HTTP/1.1" 200 3474 "http://0.0.0.0:5000/ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21,848 127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug HTTP/1.1" 200 3474 "http://0.0.0.0:5000/ad?assignmentId=debugAL4G0T&hitId=debugSDYZU2&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21 [58480] [DEBUG] GET /static/scripts/reqwest.min.js
2016-11-19 18:00:21,896 GET /static/scripts/reqwest.min.js
127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21,898 127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /static/scripts/reqwest.min.js HTTP/1.1" 200 9342 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21 [58480] [DEBUG] GET /static/scripts/dallinger.js
2016-11-19 18:00:21,899 GET /static/scripts/dallinger.js
127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /static/scripts/dallinger.js HTTP/1.1" 200 4281 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21,901 127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /static/scripts/dallinger.js HTTP/1.1" 200 4281 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:21,902 Closing connection. 
2016-11-19 18:00:21 [58480] [DEBUG] GET /static/css/dallinger.css
2016-11-19 18:00:21,904 GET /static/css/dallinger.css
127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /static/css/dallinger.css HTTP/1.1" 200 366 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:21,906 127.0.0.1 - - [19/Nov/2016:18:00:21] "GET /static/css/dallinger.css HTTP/1.1" 200 366 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:22 [58480] [DEBUG] GET /instructions/instruct-1
2016-11-19 18:00:22,750 GET /instructions/instruct-1
2016-11-19 18:00:22,753 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:22,753 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:22] "GET /instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:22,754 127.0.0.1 - - [19/Nov/2016:18:00:22] "GET /instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug HTTP/1.1" 200 933 "http://0.0.0.0:5000/consent?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&workerId=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:22 [58480] [DEBUG] GET /static/images/blue-bar.jpg
2016-11-19 18:00:22,771 GET /static/images/blue-bar.jpg
127.0.0.1 - - [19/Nov/2016:18:00:22] "GET /static/images/blue-bar.jpg HTTP/1.1" 200 10659 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:22,773 127.0.0.1 - - [19/Nov/2016:18:00:22] "GET /static/images/blue-bar.jpg HTTP/1.1" 200 10659 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:22 [58480] [DEBUG] GET /static/images/red-bar.jpg
2016-11-19 18:00:22,778 GET /static/images/red-bar.jpg
127.0.0.1 - - [19/Nov/2016:18:00:22] "GET /static/images/red-bar.jpg HTTP/1.1" 200 9260 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:22,780 127.0.0.1 - - [19/Nov/2016:18:00:22] "GET /static/images/red-bar.jpg HTTP/1.1" 200 9260 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23 [58480] [DEBUG] POST /participant/debug1D6FDP/debugSDYZU2/debugAL4G0T/debug
2016-11-19 18:00:23,109 POST /participant/debug1D6FDP/debugSDYZU2/debugAL4G0T/debug
participant post request successful.
2016-11-19 18:00:23,128 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:23,128 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:23] "POST /participant/debug1D6FDP/debugSDYZU2/debugAL4G0T/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23,129 127.0.0.1 - - [19/Nov/2016:18:00:23] "POST /participant/debug1D6FDP/debugSDYZU2/debugAL4G0T/debug HTTP/1.1" 200 466 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23 [58480] [DEBUG] GET /instructions/instruct-2
2016-11-19 18:00:23,799 GET /instructions/instruct-2
2016-11-19 18:00:23,802 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:23,802 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /instructions/instruct-2?participant_id=1 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23,804 127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /instructions/instruct-2?participant_id=1 HTTP/1.1" 200 1942 "http://0.0.0.0:5000/instructions/instruct-1?hit_id=debugSDYZU2&assignment_id=debugAL4G0T&worker_id=debug1D6FDP&mode=debug" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23 [58480] [DEBUG] GET /static/images/both-bars.jpg
2016-11-19 18:00:23,821 GET /static/images/both-bars.jpg
127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /static/images/both-bars.jpg HTTP/1.1" 200 15788 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23,823 127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /static/images/both-bars.jpg HTTP/1.1" 200 15788 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23 [58480] [DEBUG] GET /static/images/feedback.jpg
2016-11-19 18:00:23,827 GET /static/images/feedback.jpg
127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /static/images/feedback.jpg HTTP/1.1" 200 16334 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23,829 127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /static/images/feedback.jpg HTTP/1.1" 200 16334 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23 [58480] [DEBUG] GET /static/images/adjust.jpg
2016-11-19 18:00:23,834 GET /static/images/adjust.jpg
127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /static/images/adjust.jpg HTTP/1.1" 200 16515 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:23,836 127.0.0.1 - - [19/Nov/2016:18:00:23] "GET /static/images/adjust.jpg HTTP/1.1" 200 16515 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:26 [58480] [DEBUG] GET /instructions/instruct-3
2016-11-19 18:00:26,524 GET /instructions/instruct-3
2016-11-19 18:00:26,527 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:26,527 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:26] "GET /instructions/instruct-3?participant_id=1 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:26,527 127.0.0.1 - - [19/Nov/2016:18:00:26] "GET /instructions/instruct-3?participant_id=1 HTTP/1.1" 200 1234 "http://0.0.0.0:5000/instructions/instruct-2?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:27 [58480] [DEBUG] GET /instructions/instruct-ready
2016-11-19 18:00:27,517 GET /instructions/instruct-ready
2016-11-19 18:00:27,520 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:27,520 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:27] "GET /instructions/instruct-ready?participant_id=1 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:27,520 127.0.0.1 - - [19/Nov/2016:18:00:27] "GET /instructions/instruct-ready?participant_id=1 HTTP/1.1" 200 1128 "http://0.0.0.0:5000/instructions/instruct-3?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] GET /exp
2016-11-19 18:00:28,601 GET /exp
2016-11-19 18:00:28,604 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:28,604 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /exp?participant_id=1 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28,605 127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /exp?participant_id=1 HTTP/1.1" 200 1453 "http://0.0.0.0:5000/instructions/instruct-ready?participant_id=1" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_11_6) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] GET /static/scripts/raphael-min.js
2016-11-19 18:00:28,630 GET /static/scripts/raphael-min.js
127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/raphael-min.js HTTP/1.1" 200 91386 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28,632 127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/raphael-min.js HTTP/1.1" 200 91386 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] GET /static/scripts/mousetrap.min.js
2016-11-19 18:00:28,638 GET /static/scripts/mousetrap.min.js
127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 200 3839 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28,640 127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/mousetrap.min.js HTTP/1.1" 200 3839 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] GET /static/scripts/mousetrapExtension-pause.min.js
2016-11-19 18:00:28,641 GET /static/scripts/mousetrapExtension-pause.min.js
127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 200 174 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28,644 127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/mousetrapExtension-pause.min.js HTTP/1.1" 200 174 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] GET /static/scripts/helpers.js
2016-11-19 18:00:28,645 GET /static/scripts/helpers.js
127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/helpers.js HTTP/1.1" 200 1642 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28,647 127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/helpers.js HTTP/1.1" 200 1642 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] GET /static/scripts/experiment.js
2016-11-19 18:00:28,648 GET /static/scripts/experiment.js
127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/experiment.js HTTP/1.1" 200 5462 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28,650 127.0.0.1 - - [19/Nov/2016:18:00:28] "GET /static/scripts/experiment.js HTTP/1.1" 200 5462 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:28 [58480] [DEBUG] POST /node/1
2016-11-19 18:00:28,899 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-11-19 18:00:29,067 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:29,067 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:29] "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:29,068 127.0.0.1 - - [19/Nov/2016:18:00:29] "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:29 [58480] [DEBUG] GET /node/2/received_infos
2016-11-19 18:00:29,132 GET /node/2/received_infos
received infos request successful.
2016-11-19 18:00:29,148 - dallinger.db - DEBUG - Closing Dallinger DB session at flask request end
2016-11-19 18:00:29,148 Closing Dallinger DB session at flask request end
127.0.0.1 - - [19/Nov/2016:18:00:29] "GET /node/2/received_infos HTTP/1.1" 200 774 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:29,149 127.0.0.1 - - [19/Nov/2016:18:00:29] "GET /node/2/received_infos HTTP/1.1" 200 774 "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/54.0.2840.98 Safari/537.36"
2016-11-19 18:00:45 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:45,540 Closing connection. 
2016-11-19 18:00:46 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:46,478 Closing connection. 
2016-11-19 18:00:46 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:46,478 Closing connection. 
2016-11-19 18:00:46 [58480] [DEBUG] GET /ppid
2016-11-19 18:00:46,479 GET /ppid
127.0.0.1 - - [19/Nov/2016:18:00:46] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-11-19 18:00:46,480 127.0.0.1 - - [19/Nov/2016:18:00:46] "GET /ppid HTTP/1.1" 200 5 "-" "Python-urllib/2.7"
2016-11-19 18:00:46 [58480] [DEBUG] Closing connection. 
2016-11-19 18:00:46,522 Closing connection. 
2016-11-19 18:00:46 [58480] [INFO] Parent changed, shutting down: <Worker 58480>
2016-11-19 18:00:46,522 Parent changed, shutting down: <Worker 58480>
2016-11-19 18:00:46 [58480] [INFO] Worker exiting (pid: 58480)
2016-11-19 18:00:46,523 Worker exiting (pid: 58480)
