builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-005 starttime: 1448457061.07 results: success (0) buildid: 20151125035232 builduid: 9035dc94eac04bf2a63b85f3786b63fe revision: 1835baed2a38429a3cc301d21778a113d3a9e7d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.070291) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.070828) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.071173) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.004125 basedir: '/builds/slave/test' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.087092) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.087440) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.098340) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.098714) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004226 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.150237) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.150577) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.151000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.151547) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 05:11:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.9M=0s 2015-11-25 05:11:02 (35.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.187522 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.352005) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.352377) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017846 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.400852) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.401260) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1835baed2a38429a3cc301d21778a113d3a9e7d8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1835baed2a38429a3cc301d21778a113d3a9e7d8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 05:11:02,209 truncating revision to first 12 chars 2015-11-25 05:11:02,209 Setting DEBUG logging. 2015-11-25 05:11:02,209 attempt 1/10 2015-11-25 05:11:02,209 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1835baed2a38?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 05:11:02,481 unpacking tar archive at: mozilla-central-1835baed2a38/testing/mozharness/ program finished with exit code 0 elapsedTime=0.552176 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.965952) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.966326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.977975) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.978392) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 05:11:01.978887) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 9 secs) (at 2015-11-25 05:11:01.979310) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:11:02 INFO - MultiFileLogger online at 20151125 05:11:02 in /builds/slave/test 05:11:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 05:11:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:11:02 INFO - {'append_to_log': False, 05:11:02 INFO - 'base_work_dir': '/builds/slave/test', 05:11:02 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 05:11:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:11:02 INFO - 'branch': 'Firefox-Non-PGO', 05:11:02 INFO - 'buildbot_json_path': 'buildprops.json', 05:11:02 INFO - 'config_files': ('talos/linux_config.py',), 05:11:02 INFO - 'default_actions': ('clobber', 05:11:02 INFO - 'read-buildbot-config', 05:11:02 INFO - 'download-and-extract', 05:11:02 INFO - 'populate-webroot', 05:11:02 INFO - 'create-virtualenv', 05:11:02 INFO - 'install', 05:11:02 INFO - 'run-tests'), 05:11:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:11:02 INFO - 'download_minidump_stackwalk': True, 05:11:02 INFO - 'download_symbols': 'ondemand', 05:11:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:11:02 INFO - 'tooltool.py': '/tools/tooltool.py', 05:11:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:11:02 INFO - '/tools/misc-python/virtualenv.py')}, 05:11:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:11:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:11:02 INFO - 'installer_path': 'installer.exe', 05:11:02 INFO - 'log_level': 'info', 05:11:02 INFO - 'log_name': 'talos', 05:11:02 INFO - 'log_to_console': True, 05:11:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:11:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:11:02 INFO - 'opt_config_files': (), 05:11:02 INFO - 'pip_index': False, 05:11:02 INFO - 'sps_profile': False, 05:11:02 INFO - 'sps_profile_interval': 0, 05:11:02 INFO - 'suite': 'dromaeojs', 05:11:02 INFO - 'system_bits': '64', 05:11:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:11:02 INFO - 'title': 'talos-linux64-ix-005', 05:11:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:11:02 INFO - 'use_talos_json': True, 05:11:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:11:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:11:02 INFO - 'work_dir': 'build'} 05:11:02 INFO - ##### 05:11:02 INFO - ##### Running clobber step. 05:11:02 INFO - ##### 05:11:02 INFO - Running pre-action listener: _resource_record_pre_action 05:11:02 INFO - Running main action method: clobber 05:11:02 INFO - rmtree: /builds/slave/test/build 05:11:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:11:03 INFO - Running post-action listener: _resource_record_post_action 05:11:03 INFO - ##### 05:11:03 INFO - ##### Running read-buildbot-config step. 05:11:03 INFO - ##### 05:11:03 INFO - Running pre-action listener: _resource_record_pre_action 05:11:03 INFO - Running main action method: read_buildbot_config 05:11:03 INFO - Using buildbot properties: 05:11:03 INFO - { 05:11:03 INFO - "properties": { 05:11:03 INFO - "buildnumber": 220, 05:11:03 INFO - "product": "firefox", 05:11:03 INFO - "script_repo_revision": "production", 05:11:03 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 05:11:03 INFO - "repository": "", 05:11:03 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 05:11:03 INFO - "buildid": "20151125035232", 05:11:03 INFO - "slavename": "talos-linux64-ix-005", 05:11:03 INFO - "pgo_build": "False", 05:11:03 INFO - "basedir": "/builds/slave/test", 05:11:03 INFO - "project": "", 05:11:03 INFO - "platform": "ubuntu64_hw", 05:11:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 05:11:03 INFO - "slavebuilddir": "test", 05:11:03 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 05:11:03 INFO - "branch": "mozilla-central", 05:11:03 INFO - "repo_path": "mozilla-central", 05:11:03 INFO - "stage_platform": "linux64", 05:11:03 INFO - "builduid": "9035dc94eac04bf2a63b85f3786b63fe", 05:11:03 INFO - "revision": "1835baed2a38429a3cc301d21778a113d3a9e7d8" 05:11:03 INFO - }, 05:11:03 INFO - "sourcestamp": { 05:11:03 INFO - "repository": "", 05:11:03 INFO - "hasPatch": false, 05:11:03 INFO - "project": "", 05:11:03 INFO - "branch": "mozilla-central-linux64-talos", 05:11:03 INFO - "changes": [ 05:11:03 INFO - { 05:11:03 INFO - "category": null, 05:11:03 INFO - "files": [ 05:11:03 INFO - { 05:11:03 INFO - "url": null, 05:11:03 INFO - "name": "https://queue.taskcluster.net/v1/task/1qebsQUKQkmLb8MyYvyT6Q/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:11:03 INFO - } 05:11:03 INFO - ], 05:11:03 INFO - "repository": "", 05:11:03 INFO - "rev": "ef95cb431736da42d3b21ae288140225100d9f97", 05:11:03 INFO - "who": "cbook@mozilla.com", 05:11:03 INFO - "when": 1448456697, 05:11:03 INFO - "number": 6705485, 05:11:03 INFO - "comments": "merge fx-team to mozilla-central a=merge", 05:11:03 INFO - "project": "", 05:11:03 INFO - "at": "Wed 25 Nov 2015 05:04:57", 05:11:03 INFO - "branch": "mozilla-central-linux64-talos", 05:11:03 INFO - "revlink": "", 05:11:03 INFO - "properties": [ 05:11:03 INFO - [ 05:11:03 INFO - "buildid", 05:11:03 INFO - "20151125035131", 05:11:03 INFO - "Change" 05:11:03 INFO - ], 05:11:03 INFO - [ 05:11:03 INFO - "builduid", 05:11:03 INFO - "eac12e52cd914054bc412842a13c5e12", 05:11:03 INFO - "Change" 05:11:03 INFO - ], 05:11:03 INFO - [ 05:11:03 INFO - "pgo_build", 05:11:03 INFO - "False", 05:11:03 INFO - "Change" 05:11:03 INFO - ] 05:11:03 INFO - ], 05:11:03 INFO - "revision": "ef95cb431736da42d3b21ae288140225100d9f97" 05:11:03 INFO - }, 05:11:03 INFO - { 05:11:03 INFO - "category": null, 05:11:03 INFO - "files": [ 05:11:03 INFO - { 05:11:03 INFO - "url": null, 05:11:03 INFO - "name": "https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:11:03 INFO - } 05:11:03 INFO - ], 05:11:03 INFO - "repository": "", 05:11:03 INFO - "rev": "1835baed2a38429a3cc301d21778a113d3a9e7d8", 05:11:03 INFO - "who": "cbook@mozilla.com", 05:11:03 INFO - "when": 1448456692, 05:11:03 INFO - "number": 6705483, 05:11:03 INFO - "comments": "merge mozilla-inbound to mozilla-central a=merge", 05:11:03 INFO - "project": "", 05:11:03 INFO - "at": "Wed 25 Nov 2015 05:04:52", 05:11:03 INFO - "branch": "mozilla-central-linux64-talos", 05:11:03 INFO - "revlink": "", 05:11:03 INFO - "properties": [ 05:11:03 INFO - [ 05:11:03 INFO - "buildid", 05:11:03 INFO - "20151125035232", 05:11:03 INFO - "Change" 05:11:03 INFO - ], 05:11:03 INFO - [ 05:11:03 INFO - "builduid", 05:11:03 INFO - "9035dc94eac04bf2a63b85f3786b63fe", 05:11:03 INFO - "Change" 05:11:03 INFO - ], 05:11:03 INFO - [ 05:11:03 INFO - "pgo_build", 05:11:03 INFO - "False", 05:11:03 INFO - "Change" 05:11:03 INFO - ] 05:11:03 INFO - ], 05:11:03 INFO - "revision": "1835baed2a38429a3cc301d21778a113d3a9e7d8" 05:11:03 INFO - } 05:11:03 INFO - ], 05:11:03 INFO - "revision": "1835baed2a38429a3cc301d21778a113d3a9e7d8" 05:11:03 INFO - } 05:11:03 INFO - } 05:11:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:11:03 INFO - Running post-action listener: _resource_record_post_action 05:11:03 INFO - ##### 05:11:03 INFO - ##### Running download-and-extract step. 05:11:03 INFO - ##### 05:11:03 INFO - Running pre-action listener: _resource_record_pre_action 05:11:03 INFO - Running main action method: download_and_extract 05:11:03 INFO - mkdir: /builds/slave/test/build/tests 05:11:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:03 INFO - https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:11:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/test_packages.json 05:11:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/test_packages.json 05:11:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 05:11:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 05:11:04 INFO - Downloaded 1302 bytes. 05:11:04 INFO - Reading from file /builds/slave/test/build/test_packages.json 05:11:04 INFO - Using the following test package requirements: 05:11:04 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:04 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:11:04 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'jsshell-linux-x86_64.zip'], 05:11:04 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:11:04 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:04 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:11:04 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:11:04 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:11:04 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:04 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:04 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:11:04 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:11:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:04 INFO - https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:11:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:11:05 INFO - Downloaded 21507929 bytes. 05:11:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 05:11:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:05 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:11:06 INFO - Return code: 0 05:11:06 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:06 INFO - https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:11:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:11:07 INFO - Downloaded 21507929 bytes. 05:11:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 05:11:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:11:07 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:11:08 INFO - Return code: 0 05:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:08 INFO - https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:11:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:11:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:11:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:11:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:11:09 INFO - Downloaded 11131717 bytes. 05:11:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 05:11:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:11:09 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:11:09 INFO - Return code: 0 05:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:09 INFO - https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:11:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:11:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:11:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:11:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:11:10 INFO - Downloaded 56844439 bytes. 05:11:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:11:10 INFO - mkdir: /builds/slave/test/properties 05:11:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:11:10 INFO - Writing to file /builds/slave/test/properties/build_url 05:11:10 INFO - Contents: 05:11:10 INFO - build_url:https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:11:10 INFO - Running post-action listener: _resource_record_post_action 05:11:10 INFO - Running post-action listener: set_extra_try_arguments 05:11:10 INFO - ##### 05:11:10 INFO - ##### Running populate-webroot step. 05:11:10 INFO - ##### 05:11:10 INFO - Running pre-action listener: _resource_record_pre_action 05:11:10 INFO - Running main action method: populate_webroot 05:11:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:11:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:11:10 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:11:10 INFO - '../fennec_ids.txt'], 05:11:10 INFO - 'tests': ['tcheck2']}, 05:11:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:11:10 INFO - '--tppagecycles', 05:11:10 INFO - '7'], 05:11:10 INFO - 'tests': ['tsvgm']}}, 05:11:10 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:11:10 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:11:10 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:11:10 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:11:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'tests': ['damp', 'tps']}, 05:11:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['damp', 'tps']}, 05:11:10 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:11:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:11:10 INFO - 'other': {'tests': ['a11yr', 05:11:10 INFO - 'ts_paint', 05:11:10 INFO - 'tpaint', 05:11:10 INFO - 'sessionrestore', 05:11:10 INFO - 'sessionrestore_no_auto_restore']}, 05:11:10 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['a11yr', 05:11:10 INFO - 'ts_paint', 05:11:10 INFO - 'tpaint', 05:11:10 INFO - 'sessionrestore', 05:11:10 INFO - 'sessionrestore_no_auto_restore']}, 05:11:10 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['a11yr', 05:11:10 INFO - 'ts_paint', 05:11:10 INFO - 'tpaint', 05:11:10 INFO - 'sessionrestore', 05:11:10 INFO - 'sessionrestore_no_auto_restore']}, 05:11:10 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['a11yr', 05:11:10 INFO - 'ts_paint', 05:11:10 INFO - 'tpaint', 05:11:10 INFO - 'sessionrestore', 05:11:10 INFO - 'sessionrestore_no_auto_restore']}, 05:11:10 INFO - 'other_l64': {'tests': ['a11yr', 05:11:10 INFO - 'ts_paint', 05:11:10 INFO - 'tpaint', 05:11:10 INFO - 'sessionrestore', 05:11:10 INFO - 'sessionrestore_no_auto_restore']}, 05:11:10 INFO - 'other_nol64': {'tests': ['a11yr', 05:11:10 INFO - 'ts_paint', 05:11:10 INFO - 'tpaint', 05:11:10 INFO - 'sessionrestore', 05:11:10 INFO - 'sessionrestore_no_auto_restore']}, 05:11:10 INFO - 'svgr': {'tests': ['tsvgx', 05:11:10 INFO - 'tsvgr_opacity', 05:11:10 INFO - 'tart', 05:11:10 INFO - 'tscrollx', 05:11:10 INFO - 'cart']}, 05:11:10 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['tsvgx', 05:11:10 INFO - 'tsvgr_opacity', 05:11:10 INFO - 'tart', 05:11:10 INFO - 'tscrollx', 05:11:10 INFO - 'cart']}, 05:11:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'tests': ['tp5o']}, 05:11:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'talos_options': ['--e10s'], 05:11:10 INFO - 'tests': ['tp5o']}, 05:11:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'talos_options': ['--xperf_path', 05:11:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:10 INFO - 'tests': ['tp5n']}, 05:11:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:10 INFO - 'talos_options': ['--e10s', 05:11:10 INFO - '--xperf_path', 05:11:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:10 INFO - 'tests': ['tp5n']}}, 05:11:10 INFO - 'talos.zip': {'path': '', 05:11:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:11:10 INFO - Running post-action listener: _resource_record_post_action 05:11:10 INFO - ##### 05:11:10 INFO - ##### Running create-virtualenv step. 05:11:10 INFO - ##### 05:11:10 INFO - Running pre-action listener: _resource_record_pre_action 05:11:10 INFO - Running main action method: create_virtualenv 05:11:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:11:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:11:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:11:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:11:11 INFO - Using real prefix '/usr' 05:11:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:11:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:13 INFO - Installing pip.................done. 05:11:13 INFO - Return code: 0 05:11:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:11:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:11:13 INFO - Using env: {'DISPLAY': ':0', 05:11:13 INFO - 'HOME': '/home/cltbld', 05:11:13 INFO - 'LANG': 'en_US.UTF-8', 05:11:13 INFO - 'LANGUAGE': 'en_US:en', 05:11:13 INFO - 'LOGNAME': 'cltbld', 05:11:13 INFO - 'MAIL': '/var/mail/cltbld', 05:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:13 INFO - 'MOZ_NO_REMOTE': '1', 05:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:13 INFO - 'NO_EM_RESTART': '1', 05:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:13 INFO - 'PWD': '/builds/slave/test', 05:11:13 INFO - 'SHELL': '/bin/bash', 05:11:13 INFO - 'SHLVL': '1', 05:11:13 INFO - 'TERM': 'linux', 05:11:13 INFO - 'TMOUT': '86400', 05:11:13 INFO - 'USER': 'cltbld', 05:11:13 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:13 INFO - '_': '/tools/buildbot/bin/python'} 05:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:11:13 INFO - Cleaning up... 05:11:13 INFO - Return code: 0 05:11:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:11:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:11:13 INFO - Using env: {'DISPLAY': ':0', 05:11:13 INFO - 'HOME': '/home/cltbld', 05:11:13 INFO - 'LANG': 'en_US.UTF-8', 05:11:13 INFO - 'LANGUAGE': 'en_US:en', 05:11:13 INFO - 'LOGNAME': 'cltbld', 05:11:13 INFO - 'MAIL': '/var/mail/cltbld', 05:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:13 INFO - 'MOZ_NO_REMOTE': '1', 05:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:13 INFO - 'NO_EM_RESTART': '1', 05:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:13 INFO - 'PWD': '/builds/slave/test', 05:11:13 INFO - 'SHELL': '/bin/bash', 05:11:13 INFO - 'SHLVL': '1', 05:11:13 INFO - 'TERM': 'linux', 05:11:13 INFO - 'TMOUT': '86400', 05:11:13 INFO - 'USER': 'cltbld', 05:11:13 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:13 INFO - '_': '/tools/buildbot/bin/python'} 05:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:14 INFO - Downloading/unpacking psutil>=0.7.1 05:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:15 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 05:11:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:11:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:15 INFO - Installing collected packages: psutil 05:11:15 INFO - Running setup.py install for psutil 05:11:15 INFO - building 'psutil._psutil_linux' extension 05:11:15 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:11:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:11:16 INFO - building 'psutil._psutil_posix' extension 05:11:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:11:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:11:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:16 INFO - Successfully installed psutil 05:11:16 INFO - Cleaning up... 05:11:16 INFO - Return code: 0 05:11:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:11:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:11:16 INFO - Using env: {'DISPLAY': ':0', 05:11:16 INFO - 'HOME': '/home/cltbld', 05:11:16 INFO - 'LANG': 'en_US.UTF-8', 05:11:16 INFO - 'LANGUAGE': 'en_US:en', 05:11:16 INFO - 'LOGNAME': 'cltbld', 05:11:16 INFO - 'MAIL': '/var/mail/cltbld', 05:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:16 INFO - 'MOZ_NO_REMOTE': '1', 05:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:16 INFO - 'NO_EM_RESTART': '1', 05:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:16 INFO - 'PWD': '/builds/slave/test', 05:11:16 INFO - 'SHELL': '/bin/bash', 05:11:16 INFO - 'SHLVL': '1', 05:11:16 INFO - 'TERM': 'linux', 05:11:16 INFO - 'TMOUT': '86400', 05:11:16 INFO - 'USER': 'cltbld', 05:11:16 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:16 INFO - '_': '/tools/buildbot/bin/python'} 05:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:11:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:11:18 INFO - Installing collected packages: mozsystemmonitor 05:11:18 INFO - Running setup.py install for mozsystemmonitor 05:11:18 INFO - Successfully installed mozsystemmonitor 05:11:18 INFO - Cleaning up... 05:11:18 INFO - Return code: 0 05:11:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:11:18 INFO - Using env: {'DISPLAY': ':0', 05:11:18 INFO - 'HOME': '/home/cltbld', 05:11:18 INFO - 'LANG': 'en_US.UTF-8', 05:11:18 INFO - 'LANGUAGE': 'en_US:en', 05:11:18 INFO - 'LOGNAME': 'cltbld', 05:11:18 INFO - 'MAIL': '/var/mail/cltbld', 05:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:18 INFO - 'MOZ_NO_REMOTE': '1', 05:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:18 INFO - 'NO_EM_RESTART': '1', 05:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:18 INFO - 'PWD': '/builds/slave/test', 05:11:18 INFO - 'SHELL': '/bin/bash', 05:11:18 INFO - 'SHLVL': '1', 05:11:18 INFO - 'TERM': 'linux', 05:11:18 INFO - 'TMOUT': '86400', 05:11:18 INFO - 'USER': 'cltbld', 05:11:18 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:18 INFO - '_': '/tools/buildbot/bin/python'} 05:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:18 INFO - Downloading/unpacking blobuploader==1.2.4 05:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:20 INFO - Downloading blobuploader-1.2.4.tar.gz 05:11:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:11:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:11:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:21 INFO - Downloading docopt-0.6.1.tar.gz 05:11:21 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:11:21 INFO - Installing collected packages: blobuploader, requests, docopt 05:11:21 INFO - Running setup.py install for blobuploader 05:11:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:11:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:11:21 INFO - Running setup.py install for requests 05:11:21 INFO - Running setup.py install for docopt 05:11:21 INFO - Successfully installed blobuploader requests docopt 05:11:21 INFO - Cleaning up... 05:11:21 INFO - Return code: 0 05:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:11:21 INFO - Using env: {'DISPLAY': ':0', 05:11:21 INFO - 'HOME': '/home/cltbld', 05:11:21 INFO - 'LANG': 'en_US.UTF-8', 05:11:21 INFO - 'LANGUAGE': 'en_US:en', 05:11:21 INFO - 'LOGNAME': 'cltbld', 05:11:21 INFO - 'MAIL': '/var/mail/cltbld', 05:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:21 INFO - 'MOZ_NO_REMOTE': '1', 05:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:21 INFO - 'NO_EM_RESTART': '1', 05:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:21 INFO - 'PWD': '/builds/slave/test', 05:11:21 INFO - 'SHELL': '/bin/bash', 05:11:21 INFO - 'SHLVL': '1', 05:11:21 INFO - 'TERM': 'linux', 05:11:21 INFO - 'TMOUT': '86400', 05:11:21 INFO - 'USER': 'cltbld', 05:11:21 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:21 INFO - '_': '/tools/buildbot/bin/python'} 05:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:21 INFO - Running setup.py (path:/tmp/pip-c07fB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:21 INFO - Running setup.py (path:/tmp/pip-MI_MIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:22 INFO - Running setup.py (path:/tmp/pip-DWfKE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:22 INFO - Running setup.py (path:/tmp/pip-nxltZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:22 INFO - Running setup.py (path:/tmp/pip-95uulO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:22 INFO - Running setup.py (path:/tmp/pip-SceEaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:22 INFO - Running setup.py (path:/tmp/pip-WmI_XS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:22 INFO - Running setup.py (path:/tmp/pip-AbNHIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:22 INFO - Running setup.py (path:/tmp/pip-nGR_YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:22 INFO - Running setup.py (path:/tmp/pip-FRukre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:22 INFO - Running setup.py (path:/tmp/pip-5T7mBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:22 INFO - Running setup.py (path:/tmp/pip-jKt7gB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:22 INFO - Running setup.py (path:/tmp/pip-QiYM0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:22 INFO - Running setup.py (path:/tmp/pip-0PKvqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:22 INFO - Running setup.py (path:/tmp/pip-DuViE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:22 INFO - Running setup.py (path:/tmp/pip-0NAT1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:23 INFO - Running setup.py (path:/tmp/pip-AUe6Rc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:11:23 INFO - Running setup.py install for manifestparser 05:11:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Running setup.py install for mozcrash 05:11:23 INFO - Running setup.py install for mozdebug 05:11:23 INFO - Running setup.py install for mozdevice 05:11:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Running setup.py install for mozfile 05:11:23 INFO - Running setup.py install for mozhttpd 05:11:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Running setup.py install for mozinfo 05:11:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Running setup.py install for mozInstall 05:11:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:11:23 INFO - Running setup.py install for mozleak 05:11:24 INFO - Running setup.py install for mozlog 05:11:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Running setup.py install for moznetwork 05:11:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Running setup.py install for mozprocess 05:11:24 INFO - Running setup.py install for mozprofile 05:11:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Running setup.py install for mozrunner 05:11:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Running setup.py install for mozscreenshot 05:11:24 INFO - Running setup.py install for moztest 05:11:24 INFO - Running setup.py install for mozversion 05:11:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:11:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:11:24 INFO - Cleaning up... 05:11:24 INFO - Return code: 0 05:11:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:11:24 INFO - Using env: {'DISPLAY': ':0', 05:11:24 INFO - 'HOME': '/home/cltbld', 05:11:24 INFO - 'LANG': 'en_US.UTF-8', 05:11:24 INFO - 'LANGUAGE': 'en_US:en', 05:11:24 INFO - 'LOGNAME': 'cltbld', 05:11:24 INFO - 'MAIL': '/var/mail/cltbld', 05:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:24 INFO - 'MOZ_NO_REMOTE': '1', 05:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:24 INFO - 'NO_EM_RESTART': '1', 05:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:24 INFO - 'PWD': '/builds/slave/test', 05:11:24 INFO - 'SHELL': '/bin/bash', 05:11:24 INFO - 'SHLVL': '1', 05:11:24 INFO - 'TERM': 'linux', 05:11:24 INFO - 'TMOUT': '86400', 05:11:24 INFO - 'USER': 'cltbld', 05:11:24 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:24 INFO - '_': '/tools/buildbot/bin/python'} 05:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:25 INFO - Running setup.py (path:/tmp/pip-ISKDmw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:25 INFO - Running setup.py (path:/tmp/pip-dTYmlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:25 INFO - Running setup.py (path:/tmp/pip-_ZMB2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:25 INFO - Running setup.py (path:/tmp/pip-CpB7c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:25 INFO - Running setup.py (path:/tmp/pip-B27fyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:25 INFO - Running setup.py (path:/tmp/pip-grKJG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:25 INFO - Running setup.py (path:/tmp/pip-zxl5Sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:25 INFO - Running setup.py (path:/tmp/pip-sWIJ4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:25 INFO - Running setup.py (path:/tmp/pip-s5U9dj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:25 INFO - Running setup.py (path:/tmp/pip-XRTjtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:25 INFO - Running setup.py (path:/tmp/pip-rxAfzZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:25 INFO - Running setup.py (path:/tmp/pip-HixNzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:26 INFO - Running setup.py (path:/tmp/pip-SExgaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:26 INFO - Running setup.py (path:/tmp/pip-fccPyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:26 INFO - Running setup.py (path:/tmp/pip-fG9Gje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:26 INFO - Running setup.py (path:/tmp/pip-9PQjzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:26 INFO - Running setup.py (path:/tmp/pip-bfwkSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:28 INFO - Downloading blessings-1.5.1.tar.gz 05:11:28 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:28 INFO - Installing collected packages: blessings 05:11:28 INFO - Running setup.py install for blessings 05:11:28 INFO - Successfully installed blessings 05:11:28 INFO - Cleaning up... 05:11:28 INFO - Return code: 0 05:11:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:28 INFO - Reading from file tmpfile_stdout 05:11:28 INFO - Current package versions: 05:11:28 INFO - argparse == 1.2.1 05:11:28 INFO - blessings == 1.5.1 05:11:28 INFO - blobuploader == 1.2.4 05:11:28 INFO - docopt == 0.6.1 05:11:28 INFO - manifestparser == 1.1 05:11:28 INFO - mozInstall == 1.12 05:11:28 INFO - mozcrash == 0.16 05:11:28 INFO - mozdebug == 0.1 05:11:28 INFO - mozdevice == 0.47 05:11:28 INFO - mozfile == 1.2 05:11:28 INFO - mozhttpd == 0.7 05:11:28 INFO - mozinfo == 0.9 05:11:28 INFO - mozleak == 0.1 05:11:28 INFO - mozlog == 3.1 05:11:28 INFO - moznetwork == 0.27 05:11:28 INFO - mozprocess == 0.22 05:11:28 INFO - mozprofile == 0.27 05:11:28 INFO - mozrunner == 6.11 05:11:28 INFO - mozscreenshot == 0.1 05:11:28 INFO - mozsystemmonitor == 0.0 05:11:28 INFO - moztest == 0.7 05:11:28 INFO - mozversion == 1.4 05:11:28 INFO - psutil == 3.1.1 05:11:28 INFO - requests == 1.2.3 05:11:28 INFO - wsgiref == 0.1.2 05:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:11:28 INFO - Using env: {'DISPLAY': ':0', 05:11:28 INFO - 'HOME': '/home/cltbld', 05:11:28 INFO - 'LANG': 'en_US.UTF-8', 05:11:28 INFO - 'LANGUAGE': 'en_US:en', 05:11:28 INFO - 'LOGNAME': 'cltbld', 05:11:28 INFO - 'MAIL': '/var/mail/cltbld', 05:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:28 INFO - 'MOZ_NO_REMOTE': '1', 05:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:28 INFO - 'NO_EM_RESTART': '1', 05:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:28 INFO - 'PWD': '/builds/slave/test', 05:11:28 INFO - 'SHELL': '/bin/bash', 05:11:28 INFO - 'SHLVL': '1', 05:11:28 INFO - 'TERM': 'linux', 05:11:28 INFO - 'TMOUT': '86400', 05:11:28 INFO - 'USER': 'cltbld', 05:11:28 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:28 INFO - '_': '/tools/buildbot/bin/python'} 05:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:11:28 INFO - Cleaning up... 05:11:28 INFO - Return code: 0 05:11:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2750030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2981210>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:11:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:11:28 INFO - Using env: {'DISPLAY': ':0', 05:11:28 INFO - 'HOME': '/home/cltbld', 05:11:28 INFO - 'LANG': 'en_US.UTF-8', 05:11:28 INFO - 'LANGUAGE': 'en_US:en', 05:11:28 INFO - 'LOGNAME': 'cltbld', 05:11:28 INFO - 'MAIL': '/var/mail/cltbld', 05:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:28 INFO - 'MOZ_NO_REMOTE': '1', 05:11:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:28 INFO - 'NO_EM_RESTART': '1', 05:11:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:28 INFO - 'PWD': '/builds/slave/test', 05:11:28 INFO - 'SHELL': '/bin/bash', 05:11:28 INFO - 'SHLVL': '1', 05:11:28 INFO - 'TERM': 'linux', 05:11:28 INFO - 'TMOUT': '86400', 05:11:28 INFO - 'USER': 'cltbld', 05:11:28 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:28 INFO - '_': '/tools/buildbot/bin/python'} 05:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:28 INFO - Downloading/unpacking jsonschema 05:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:30 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:11:30 INFO - Downloading/unpacking functools32 (from jsonschema) 05:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:30 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:30 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:30 INFO - warning: no files found matching '*.txt' 05:11:30 INFO - no previously-included directories found matching 'build' 05:11:30 INFO - no previously-included directories found matching 'dist' 05:11:30 INFO - no previously-included directories found matching '.git*' 05:11:30 INFO - Installing collected packages: jsonschema, functools32 05:11:30 INFO - Running setup.py install for functools32 05:11:30 INFO - warning: no files found matching '*.txt' 05:11:30 INFO - no previously-included directories found matching 'build' 05:11:30 INFO - no previously-included directories found matching 'dist' 05:11:30 INFO - no previously-included directories found matching '.git*' 05:11:31 INFO - Successfully installed jsonschema functools32 05:11:31 INFO - Cleaning up... 05:11:31 INFO - Return code: 0 05:11:31 INFO - Running post-action listener: _resource_record_post_action 05:11:31 INFO - Running post-action listener: _start_resource_monitoring 05:11:31 INFO - Starting resource monitoring. 05:11:31 INFO - ##### 05:11:31 INFO - ##### Running install step. 05:11:31 INFO - ##### 05:11:31 INFO - Running pre-action listener: _resource_record_pre_action 05:11:31 INFO - Running main action method: install 05:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:31 INFO - Reading from file tmpfile_stdout 05:11:31 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:31 INFO - Reading from file tmpfile_stdout 05:11:31 INFO - Output received: 05:11:31 INFO - Usage: mozinstall [options] installer 05:11:31 INFO - Options: 05:11:31 INFO - -h, --help show this help message and exit 05:11:31 INFO - -d DEST, --destination=DEST 05:11:31 INFO - Directory to install application into. [default: 05:11:31 INFO - "/builds/slave/test"] 05:11:31 INFO - --app=APP Application being installed. [default: firefox] 05:11:31 INFO - mkdir: /builds/slave/test/build/application 05:11:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:11:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:11:45 INFO - Reading from file tmpfile_stdout 05:11:45 INFO - Output received: 05:11:45 INFO - /builds/slave/test/build/application/firefox/firefox 05:11:45 INFO - Running post-action listener: _resource_record_post_action 05:11:45 INFO - ##### 05:11:45 INFO - ##### Running run-tests step. 05:11:45 INFO - ##### 05:11:45 INFO - Running pre-action listener: _resource_record_pre_action 05:11:45 INFO - Running main action method: run_tests 05:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:45 INFO - Python 2.7.3 05:11:45 INFO - Return code: 0 05:11:45 INFO - grabbing minidump binary from tooltool 05:11:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:11:45 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a13220>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x297f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:11:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:11:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:45 INFO - Return code: 0 05:11:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:11:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:11:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] in /builds/slave/test/build 05:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost 05:11:45 INFO - Using env: {'DISPLAY': ':0', 05:11:45 INFO - 'HOME': '/home/cltbld', 05:11:45 INFO - 'LANG': 'en_US.UTF-8', 05:11:45 INFO - 'LANGUAGE': 'en_US:en', 05:11:45 INFO - 'LOGNAME': 'cltbld', 05:11:45 INFO - 'MAIL': '/var/mail/cltbld', 05:11:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:45 INFO - 'MOZ_NO_REMOTE': '1', 05:11:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:45 INFO - 'NO_EM_RESTART': '1', 05:11:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:45 INFO - 'PWD': '/builds/slave/test', 05:11:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:45 INFO - 'SHELL': '/bin/bash', 05:11:45 INFO - 'SHLVL': '1', 05:11:45 INFO - 'TERM': 'linux', 05:11:45 INFO - 'TMOUT': '86400', 05:11:45 INFO - 'USER': 'cltbld', 05:11:45 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689', 05:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:45 INFO - '_': '/tools/buildbot/bin/python'} 05:11:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] with output_timeout 3600 05:11:45 INFO - mozversion INFO | application_buildid: 20151125035232 05:11:45 INFO - mozversion INFO | application_changeset: 1835baed2a38429a3cc301d21778a113d3a9e7d8 05:11:45 INFO - mozversion INFO | application_display_name: Nightly 05:11:45 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:45 INFO - mozversion INFO | application_name: Firefox 05:11:45 INFO - mozversion INFO | application_remotingname: firefox 05:11:45 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 05:11:45 INFO - mozversion INFO | application_vendor: Mozilla 05:11:45 INFO - mozversion INFO | application_version: 45.0a1 05:11:45 INFO - mozversion INFO | platform_buildid: 20151125035232 05:11:45 INFO - mozversion INFO | platform_changeset: 1835baed2a38429a3cc301d21778a113d3a9e7d8 05:11:45 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 05:11:45 INFO - mozversion INFO | platform_version: 45.0a1 05:11:45 INFO - 2015-11-25 05:11:45,958 DEBUG : using testdate: 1448457105 05:11:45 INFO - 2015-11-25 05:11:45,959 DEBUG : actual date: 1448457105 05:11:45 INFO - 2015-11-25 05:11:45,967 INFO : starting webserver on 'localhost:58440' 05:11:45 INFO - 2015-11-25 05:11:45,968 INFO : Starting test suite talos-linux64-ix-005 05:11:45 INFO - 2015-11-25 05:11:45,968 INFO : Starting test dromaeo_css 05:11:45 INFO - 2015-11-25 05:11:45,968 DEBUG : operating with platform_type : linux_ 05:11:45 INFO - 2015-11-25 05:11:45,969 INFO : Initialising browser for dromaeo_css test... 05:11:45 INFO - 2015-11-25 05:11:45,986 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMBFVb3/profile http://localhost:58440/getInfo.html 05:11:49 INFO - 2015-11-25 05:11:49,587 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:11:49 INFO - 2015-11-25 05:11:49,587 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:11:49 INFO - 2015-11-25 05:11:49,587 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:11:49 INFO - 2015-11-25 05:11:49,587 DEBUG : BROWSER_OUTPUT: __metrics 05:11:49 INFO - 2015-11-25 05:11:49,736 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:11:50 INFO - 2015-11-25 05:11:50,757 INFO : Browser initialized. 05:11:50 INFO - 2015-11-25 05:11:50,757 INFO : Running cycle 1/1 for dromaeo_css test... 05:11:50 INFO - 2015-11-25 05:11:50,758 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMBFVb3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:12:02 INFO - 2015-11-25 05:12:02,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 141541376 05:12:02 INFO - 2015-11-25 05:12:02,631 DEBUG : BROWSER_OUTPUT: 05:12:53 INFO - 2015-11-25 05:12:53,749 DEBUG : BROWSER_OUTPUT: 1448457173747 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:15:05 INFO - 2015-11-25 05:15:05,526 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58440/tests/dromaeo/cssquery-ext.html) 05:15:06 INFO - 2015-11-25 05:15:06,530 DEBUG : BROWSER_OUTPUT: RSS: Main: 228913152 05:15:06 INFO - 2015-11-25 05:15:06,530 DEBUG : BROWSER_OUTPUT: 05:18:08 INFO - 2015-11-25 05:18:08,962 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/dromaeo/cssquery-ext.html (next: http://localhost:58440/tests/dromaeo/cssquery-jquery.html) 05:18:09 INFO - 2015-11-25 05:18:09,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 220241920 05:18:09 INFO - 2015-11-25 05:18:09,528 DEBUG : BROWSER_OUTPUT: 05:21:11 INFO - 2015-11-25 05:21:11,531 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58440/tests/dromaeo/cssquery-mootools.html) 05:21:12 INFO - 2015-11-25 05:21:12,113 DEBUG : BROWSER_OUTPUT: RSS: Main: 214163456 05:21:12 INFO - 2015-11-25 05:21:12,114 DEBUG : BROWSER_OUTPUT: 05:24:14 INFO - 2015-11-25 05:24:14,074 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58440/tests/dromaeo/cssquery-prototype.html) 05:24:14 INFO - 2015-11-25 05:24:14,661 DEBUG : BROWSER_OUTPUT: RSS: Main: 200540160 05:24:14 INFO - 2015-11-25 05:24:14,661 DEBUG : BROWSER_OUTPUT: 05:27:17 INFO - 2015-11-25 05:27:17,380 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58440/tests/dromaeo/cssquery-yui.html) 05:27:17 INFO - 2015-11-25 05:27:17,965 DEBUG : BROWSER_OUTPUT: RSS: Main: 203505664 05:27:17 INFO - 2015-11-25 05:27:17,966 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,535 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/dromaeo/cssquery-yui.html (next: http://localhost:58440/tests/dromaeo/cssquery-dojo.html) 05:30:20 INFO - 2015-11-25 05:30:20,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 246390784 05:30:20 INFO - 2015-11-25 05:30:20,785 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,786 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:30:20 INFO - 2015-11-25 05:30:20,787 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:30:20 INFO - 2015-11-25 05:30:20,787 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:30:20 INFO - 2015-11-25 05:30:20,787 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:30:20 INFO - 2015-11-25 05:30:20,788 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2642.286582622691;2711.4170453352535;2725.6320396746432;2733.4396448728;2747.4917140329035;4464.39730636364;4593.931091033629;4604.769761511923;4610.07798440312;4633.142868569313;4550.863474095782;4575.862724118273;4644.047970166116;4646.976765116178;4715.03341814928;4299.204647140274;4303.763293018883;4309.82760689572;4348.826046958118;4356.825726970933;4150.3359462486005;4397.428334316534;4402.823887044515;4438.400815889846;4453.710508816933;4139.91720165595;4210.431591735107;4255.787210639471;4260.147970405931;4267.551907049746;4285.57144285572;4287.699861009731;4294.098239369736;4332.501762297345;4340.587644173798;4115.938260926088;4277.486701595797;4296.9140617187795;4310.935335969963;4345.217860785057;4474.597286244221;4481.3726078349055;4492.07912377965;4603.654725895572;4632.976835115836;4953.504649535053;4977.975110124463;4981.750912454399;4986.52628000343;5034.119029169918;4820.325154478376;5009.373828271466;5009.649324547283;5010.448850626428;5021.799128034874;4569.794359253809;4591.586757191869;4600.631949444037;4658.254679251371;4735.029318989599;5124.5131712487255;5139.486051394831;5141.948580514222;5145.151050076695;5145.588352931756;4679.415073115861;4935.827246046371;4942.678725882806;4945;4975.4527002029745;4860.513948605182;4914.926276105862;4946.901061978812;4975.825846095442;4989.90020199594;4912.1649319615035;4929.728864912464;4933.309336692866;4945.802167913315;4988.45127036033;4908.509149085063;4909.116359055333;4914.557689807934;4917.0903382874285;4968.701877887338;4025.0742329264435;4065.593440655911;4110.321796903478;4113.938290925638;4144.70987030911;3646.3072016316814;3650.990099009907;3657;3672.210474747942;3682.576503702055;4293.377460268306;4296.334068219431;4304.418903448057;4336.804843782037;4340.348947657813;4785;4906.435759887659;4908.214685650279;4934.309196712463;4949.48030456804;4294.463192100988;4296.0548679290505;4310.439642846472;4321.070969741458;4329.826806927687;4442.844500442469;4454.888627784331;4457.910841783211;4458.286674132188;4490.057088011489;4528.049109686936;4636.420447444069;4647.302904564207;4648.581627653527;4809;4923.606111510999;5007.273945277987;5020.422651395136;5030.37120359955;5056.494350565061;4723.315119307613;4724.811007559659;4725.527447255247;4725.692829966041;4763.261694437368;4710.364100846342;4890.217565189552;4896.1921282987905;4916.041371932439;4932.802687892444;4894.094592500399;4919.557239848431;4941.579965702956;4944.307796908508;5000.849974500771;4875.780589873535;4894.314795928502;4900.705957642553;4901.289313049517;4905.656604037683;4833.758312084452;4865.464798872051;4865.975670121768;4876.683015603974;4908.705477671351;4598.287265473857;4687.9296810547185;4705.364775755366;4807.735574543433;4840.201366774443;4816.95183048165;4861.027794441055;4886.951130488792;4912.312276281395;4941.530554597308;4785;4801.567858892717;4816.301636262792;4818.277258411266;4855.562999330077 05:30:20 INFO - 2015-11-25 05:30:20,792 DEBUG : BROWSER_OUTPUT: |1;ext.html;490.34538890581075;494.40424288732083;558.1272567856772;574.6552068758743;581.9010768169412;21618.351449456502;32732;33020.66979330209;33290.33419331612;33292.33415331692;33795.32409351812;34202.65797342026;34207.14482137948;34235.14412139698;34281.142971425725;6319.273283572379;6408.391202835724;6419.87160256797;7827;7932.603369831514;9397.671081512148;9557.713268601952;9578.473183974878;9587.328886977913;9595.568199431042;9701.951490242538;9853.704388868346;9854.90145098547;9857.95071024644;9860.161886239679;6668.433183179435;6883.208431030445;9657.75855603612;10114.089731924161;10436.060754532053;9895.802083958353;10052.69841904744;10054.949725251327;10055.446950417723;10062.39625622465;36815;37024.25951480982;37262.441063384074;37339;37527.624723752684;2566.1659960512907;2701.797365197618;2732.5764506501523;2745.8489783061923;2760.4479104179245;27702.168934931986;27994.86002569974;28173.57739633907;28231.435371292664;28342.29144271389;3170.889018884328;3244.156519304974;3250.7074363307415;3318.0543545089777;3320.352531256406;223421.99999999837;223798;224677;224790;225006;27420.314492137855;52662;53193;53284;53296.20055699167;31843.681563184535;31971;32019.199520012186;54541.454585454434;55624.16563751547;12167.878321216851;12403.875961240452;12414.62756117318;12420.316882571364;12428.440720167615;9680.903190968;9862.556184971694;9922.652707155215;9935.602575896883;9935.602575897028;17364;17774.20016099253;17810.465686029438;17871.106444677713;17872.91063544674;6302.747890084437;6647.368499992493;6708.161479815023;6710.530262881649;6762.154730658668;809.4859764049871;869.6564856881496;966.5505539923829;999.480270259461;1015.5277795824977;25771;25983;26337.47325053488;26384;26439.735602643726;909.1544863277215;933.9112784285481;972.5040229483014;984.542187882638;988.4365911430416;12446.937765311115;12507.187032842836;12565.246085234916;12862.099653024386;13136.080474366894;266253;272033.9194912078;273510;273709;274077;41772.164556708696;41911.580884191375;42017.57982420198;42087.15825683469;42097.579024209976;722.6892436252357;792.3146478296233;1124.791913496006;1179.9056075513768;1184.0705046538426;21323.36029919105;21512.67730984005;22128.557428851655;23164.884175579013;23643.881780590986;25471.872640636677;26289.868550657124;35650.46524302189;35978.64021359858;36006.63993360033;316999;325131;325848;326179;327822;26838;27353.589696154973;27356.58965115485;27553.724462755115;27578.310542236606;33699;37252.627473726;37772.62227377691;38582.4212636805;38638.2272354557;30854.228644284074;31389.686103138676;32189.195270118435;32645.673543264264;32805.671943280264;31720.206994825316;31973.520397194527;32007.839960800047;33936.83031584826;35489.64510354863;31653.683463165074;31938;32010.67989320077;32185.67814321827;32277.193070173435;25871.741282586932;26552.601710974737;26787.86606066957;26964.730352697006;27138.286171382704;21038.99468843112;21083.262085826922;21140.78859211388;21176.682349765073;21202.15191392327 05:30:20 INFO - 2015-11-25 05:30:20,793 DEBUG : BROWSER_OUTPUT: |2;jquery.html;328.28925376559744;328.54988665528225;334.9145967778216;338.25921232500804;339.57722635319016;11078.169137314713;11452.141089418306;14700.705985880277;15105.320260588287;15114.093154410742;12958.611241662742;13103;13107.148035377717;13248.47006119754;13606.523771667993;4764.166270902596;4805.087033463648;4937.308776771255;4938.950610493885;4942.456329803718;3437.7077948374417;3529.982350088259;4869.829555965542;5473.972630136864;5617.129344951519;5520.558355331564;5522.3649280332875;5541.944580554204;5558.499735023847;5570.136628822539;5035.269885866547;5568.49883510484;5594.832155035356;5597.524210442118;5600.719964001783;4941.703497790144;4945.975270123627;4950.8762280943265;4959.603231741452;4960.826371076995;19051.714224286647;19059.42821715351;19397.127129279073;19440.22239110434;19540.31608893682;1808.1230603157458;1850.5651171974575;1917.6452356314126;1971.1918113573365;2010.88940108294;12393.256404615662;12605.180663256859;12667.556635517712;12758.106932514727;13114.934425327907;1750.8249175082517;1784.50925995351;1786.0623172834237;1800.0459756329167;1833.1933949062504;51030.744846275906;51449.416196983104;52127;52964;53724.194137088365;9373.531323433801;9598.32811703188;9686.370385924893;9694.51527423626;9700.708978730649;12223.449944752507;12230.082743794073;12258.754824903452;12273.877261227452;12278.263304201777;6804.727810887608;6893.827654308682;6925.58446493212;6953.9916712076465;7033.507654464139;6536.640484773383;6564.671766411756;6599.769008084694;6631.071649969008;6648.069270302161;5740.512056475163;5770.2498675171955;5788.18965344852;5817.970910145506;5818.0981947798155;1411.3789932429697;1465.5456808389433;1626.7966504186977;1661.1279078483892;1684.5704345391846;848.4866655673352;884.1114679746813;885.8051228729669;971.9211675040699;986.7681094942683;15912.204389780465;16194.02835829854;16203.108829014282;16608.2526286315;16680.74978875318;760.3574979142616;892.6875593542338;901.3285102598475;903.11945852793;904.6697955246287;8834.469931804113;9145.954270228607;9179.908200918038;9185;9258.583363748512;506234;508269;509420;524712;525804;58836.41163588395;61528.69235653793;61983.21075551611;62416.37583624106;62419.68790156111;1053.7365658585354;1060.6022741471947;1062.984055239172;1065.0148612533376;1069.8983596558314;13406.530771422953;14148;14365;14533.491327803898;14645.560633181021;29173.85413072921;30099.398012040074;30327;30368.84815575908;32937.83531082329;35417.645823542465;35923;36501.63498365089;36563.45154822733;37194.44208336823;8446.915530844859;8459.280961118375;8466.74599762008;8516.91483085186;8832.911670883466;13456.663583410493;13510.662233444242;15846.366145354057;16538.173091345627;16902.40841570539;13001.804972925604;13609.591712248648;13826.585202443943;13848.930755346159;14002.649933751738;13379.197248165141;13758.65603359924;13821.585352439442;13872;14161.150330979763;10138.49307534635;10435.37387756737;12227.205231659913;12436.253824770181;13611;8656.96116466028;8683.348748843735;8691.565421729014;8695;8829.16122968317;11682.47428865686;13352.732945340844;13382.13016153947;13435.395407206504;13922.09506382082 05:30:20 INFO - 2015-11-25 05:30:20,797 DEBUG : BROWSER_OUTPUT: |3;mootools.html;891.3448615267781;1572.897761645493;1627.4873414874314;1630.951071467856;1666.8416500432463;5464.508194262516;6953.582785032895;7137.179224389197;7154.105736782909;7163.068801055869;6921.273266307041;7072.752453664123;7072.929270707292;7087;7097.929020709791;1671.607172314505;1706.2066139245248;2040.081963116596;2042.305616090529;2065.2874758208395;2056.249468943832;2063.6904464330396;2067.080149333547;2072.399004288762;2073.3365323096614;2034.2269937423832;2040.8673436226597;2078.3557097299886;2082.3232449453913;2084.551821358408;2045.9897700511551;2069.720587720661;2087.0608226298073;2098.4544018555207;2098.9580208395823;2091.1426315210692;2110.873347599149;2111.630464668692;2115.2279418012467;2116.291042500764;6906;6924.515283930127;6963.547369420971;6970.616616086113;6984.615846128461;3953.2488827122756;3979.9403008954887;3988.0428697112484;4003.959960400417;4026.798660067014;2236.384994126612;2541.8220724549287;2653.3499292673228;2654.482375936693;2654.9070782522713;763.7975936376864;768.8385439057805;782.0741155055712;857.5840717252128;888.9688860889838;9151.084891510796;9157.130072643089;9178.541072946393;9181.403208791475;9186.678466253648;3821.6560509554274;3866.1881004988704;3916.099297161669;3972.2254160439015;3981.2634662587516;8055.113937466874;8064.879026814602;8066.838663226703;8075.152109028585;8080.4343695940715;4062.370332598452;4078.57174996627;4078.6737061035046;4088.8364465421646;4090.079732060322;3712.1647629283193;3712.7215458840697;3717.126475278307;3719.2189640175866;3723.3670276053135;2658.282263788805;2798.398344355973;3233.272513684449;3255.706986371238;3388.2376465295606;1063.2875973098044;1071.2768881005445;1071.5285274479204;1076.1229597877705;1078.2775540387959;805.5931754463958;806.2421323955463;834.0533494483683;834.5660256666499;981.1415011864618;6326.304106548276;6330.493560515148;6393.7762178324465;6406.327335629785;6410.583312084793;1010.0858722855827;1012.5645972232011;1019.9643012494527;1027.7276521721749;1051.9684409467727;5034.647574669812;5119.974400127976;5125.359330083739;5127.102757017507;5128.743562821844;7983.281504664608;8381.58092095405;8382.622781974826;8386;8405.915940840514;8212.876806847902;8236.917630823615;8275.131111233355;8284;8345.749627511184;6126.08108783686;6216.036514340284;6232.345603711636;6261.655608941461;6307.400796924285;6032.819015429544;6055.666938318434;6086.6652334122045;6111;6122.173506576555;7267.527610705287;7348.338649521569;7357.264273572814;7453.701851926079;7504.399648028254;7498.812529686801;7585.810354741176;7593;7638.236176382317;7669.118051424158;5815.244018277597;5821.621594596338;5826.475617194473;5832.358440571449;5833.241678581757;5083.364579427572;5793.971030144822;6232.594881332699;6234.0337247726675;6251.437370636664;6045.758169673164;6117.663528505975;6185.628862268279;6229.190205273286;6231.1276421300145;5979.850503737442;6107.083937409424;6114.991026480759;6155.72299246526;6237.064440333987;5838.678872662044;5866.2373891393845;5918.082697181943;5919.615225010532;5980.3421623622235;5553.055980483408;5553.305836770403;5584.4136365681015;5586.078297080936;5593.804216852391;5757.683327416865;5785.537157027344;5795.39148389413;5804.65172089676;5807.8548036299435 05:30:20 INFO - 2015-11-25 05:30:20,797 DEBUG : BROWSER_OUTPUT: |4;prototype.html;297.93743313904076;303.36596513287225;305.22472918786286;310.78866370867803;311.64161214603206;5279.8416047518585;5324.3610766708;5326.627136100475;5342.2253773202865;5374.032674118657;5244.947550524494;5252.343457067866;5253.634055145661;5263.76313065912;5276.841694749154;3889.883303500885;4148.6266236038755;4149.792510374469;4155.54555905433;4155.646770024537;3994.840206391741;4017.959820401788;4150.149219410029;4157.605027522381;4163.958360416387;4105.178964207155;4110.095778928646;4113.03343714227;4114.691398145151;4118.979405102985;3856.730028897978;3963.7819919904396;3992.2414741199077;4106.568810274923;4117.9176416471655;3637.7635453695425;3637.999550123711;3643.526341575605;3643.6538528839983;3648.106213977566;5086.31334769805;5094.2358646203365;5096.592272618182;5107.744612769384;5112.846614601568;4754.7147171169845;4757.26262429324;4759.190937540592;4763.333133361332;4770.093682200406;5452.236686863842;5581.944180558183;5591.524720398771;5595.132754423071;5601.215829783833;3980.44273801668;3981.542122655903;3982.143839074584;3985.940210896839;3986.960130398688;5884.882302353972;5886.058230683113;5892.675902825343;5896.734646940898;5897.76408943646;4104.979475102606;4108.67130629549;4108.815103320358;4110.280700877334;4111.177764447063;3884.261990221849;3976.4830572025453;4160.979195104005;4168.103857670555;4170.124273902515;4614.538546145426;4640.095181439587;4647.512011238839;4649.279361698942;4650.209464390994;5599.944000559943;5609.298837645294;5610.102383618601;5616.129499927436;5617.101263797855;1417.7873319002233;1420.4815242436437;1422.7936949142315;1423.1887823940463;1423.4163992762917;965.6475386483883;967.2117224462041;977.3061126600194;991.0881988570533;997.2371136080903;4913.65604407688;4928.039032388721;4930.704157750546;4931.50684931504;4932.53140951609;5807.883842323215;5810.302763668303;5813.069908814569;5815.73829177688;5823.70881455934;4917.1149193145575;4924.285978533093;4928.162212423897;4928.704277743347;4929;3065.739412149948;3101.255698632312;3131.827749473801;3138.0585824252635;3138.8116712997294;5535.308086489189;5588.189712491747;5592.468715472024;5603.075492543685;5603.075492543685;6030.5778595497895;6037.758489660365;6040.909386359208;6041.214642096499;6041.879162416727;5567.387587365387;5578.581606379456;5581.134924086773;5583.581231407661;5590.077637189981;5654.840757644591;5847.093700476433;5847.853803654943;5860.531157507304;5863.061910094364;5745.597808153389;5747.281589801275;5749.195112684311;5752.590615299278;5757.539396848326;5582.49757521825;5583.804566840141;5584.218209450599;5587.161925711176;5588.357338905916;3956.2680904032845;3988.6011398859882;4007.0583412897363;4027.979860100798;4037.6366127048705;5824.126381042877;5849.824505264849;5849.853753656193;5851.824445266649;5862.618929769551;5598.720063996702;5636.915446268391;5641.464060914206;5642.040853054908;5647.435256474319;5515.889682206413;5599.8880022400135;5623.381428042994;5642.351129620146;5652.915206271991;5511.559075274049;5523.005858945428;5534.695591742493;5536.806211782568;5537.390887002507;5409.86475338104;5592.049351610155;5632.5775566831835;5663.3770285267765;5672.744726487401;5356.5982551308025;5435.646682965595;5461.481159289861;5469.288992430958;5474.206240095244 05:30:20 INFO - 2015-11-25 05:30:20,800 DEBUG : BROWSER_OUTPUT: |5;yui.html;621.6456619726755;709.6664567653202;729.0777166883892;754.1026178847175;755.576876948909;11286.774264514705;11479.942600286997;11493.482793274312;11495.42522873855;11537.596184133557;11849.46677399517;12079.094067944918;12114.152009359348;12141;12148.939255303732;369.5879094809295;377.11378261086503;773.7214602743009;779.3648176735942;786.4376970466116;770.3529035610086;775.5230533222056;782.37800948246;782.5852298281919;782.8590853646341;378.08503421719365;813.1177672225642;816.873384625381;818.1286929420148;818.8321394114192;384.1471932310267;784.6116172494594;796.8884356190138;798.3892322373389;800.0719165767681;587.3040447070235;591.2727345365225;591.2845456997039;591.328841764596;591.4735885062298;11451.198416110876;11482.425878706032;11501.42492875353;11501.884981150166;11510.712232194179;10285.280030397875;10464.476776161238;10850.294730842548;10957.83563246552;11333;8518.40371174018;8618.698345557876;8803.383763136582;9195.310351723647;9242.21441177494;3241.6272128705027;3242.400155971129;3281.8523166457567;3298.472244440902;3313.2710803623163;37082.629173707915;41702;41995.7900210497;42284.788576056926;42398.36402453966;9254.305927055499;9327.253819694408;9373.625054997723;9401.388909720847;9444.433333999981;16435.424760133337;16472.917635411985;16479.176041197894;16500.6699866002;16704.916475417544;6373.649449280241;6473.449756770728;6501.382368674968;6559.8032059038305;6815.965920170367;3535.7171426285836;3536.664016918389;3609.3864043112735;3652.342578335872;3736.2714270717493;2647.377866201441;2676.2372723773633;2679.7856171506232;2680.624712540246;2681.8870168880085;454.0895504513429;471.54260367443527;474.67247599156474;492.44845772734766;508.12602324002654;540.7863893761942;549.3325609384617;561.3039830610014;574.7270046727771;589.6845187824579;7443.404527637885;7457;7470.738524151738;7547.622618869034;7613.390928725688;322.0340662498416;426.42858569516994;440.2010351212539;479.4222961331587;509.8317555206773;3723.3670276053135;3729.627037296303;3733.663970242691;3796.6393192646656;3837.2901013312626;49824;53081.2037819433;54076.729616351666;54254.45745542494;54283;38048.61951380506;38500.8074959629;44818.77590612026;45960.770196149475;46270;37663;38898.22203555913;39360.606393936265;39461.80269098636;39469.605303947166;7039.472039596948;7285.162206346126;7469.626518674153;7526.09686837583;7613.428992825449;30664.926727564427;31125.688743112278;31203.37593248168;31377.90177343782;31633;37824.81087594544;38326;40480.797596011835;40653.39019914763;40778;9199.494027828267;9439.52802359893;9451.385659932082;9784.657536986171;9787.314887957775;10455.163586912877;10707.946460267649;10824;10985.945070274598;11100.833487497532;10178.18574514052;10266.640667576898;10302.742431438974;10434.113100386248;10725.517351719036;10016.298859079794;10034.548445319831;10073.546690398804;10399.27205095636;10419.114375278186;9789.412635241624;10005.599776008878;10046.146077583488;10231.28381013322;10273.178145748472;7986.201379861967;8119.5534245617055;8196.180381961994;9108.180263776292;9260.39807412516;8220.917790822015;8701.216890479887;8703.43427677199;9126.17864392208;9216.677416290398 05:30:20 INFO - 2015-11-25 05:30:20,800 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,802 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: __startTimestamp1448458220785__endTimestamp 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:30:20 INFO - 2015-11-25 05:30:20,801 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,802 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4591.37 Median:4715.03 stddev:469.18 (10.0%) stddev-sans-first:445.00 05:30:20 INFO - 2015-11-25 05:30:20,802 DEBUG : BROWSER_OUTPUT: Values: 2642.3 2711.4 2725.6 2733.4 2747.5 4464.4 4593.9 4604.8 4610.1 4633.1 4550.9 4575.9 4644.0 4647.0 4715.0 4299.2 4303.8 4309.8 4348.8 4356.8 4150.3 4397.4 4402.8 4438.4 4453.7 4139.9 4210.4 4255.8 4260.1 4267.6 4285.6 4287.7 4294.1 4332.5 4340.6 4115.9 4277.5 4296.9 4310.9 4345.2 4474.6 4481.4 4492.1 4603.7 4633.0 4953.5 4978.0 4981.8 4986.5 5034.1 4820.3 5009.4 5009.6 5010.4 5021.8 4569.8 4591.6 4600.6 4658.3 4735.0 5124.5 5139.5 5141.9 5145.2 5145.6 4679.4 4935.8 4942.7 4945.0 4975.5 4860.5 4914.9 4946.9 4975.8 4989.9 4912.2 4929.7 4933.3 4945.8 4988.5 4908.5 4909.1 4914.6 4917.1 4968.7 4025.1 4065.6 4110.3 4113.9 4144.7 3646.3 3651.0 3657.0 3672.2 3682.6 4293.4 4296.3 4304.4 4336.8 4340.3 4785.0 4906.4 4908.2 4934.3 4949.5 4294.5 4296.1 4310.4 4321.1 4329.8 4442.8 4454.9 4457.9 4458.3 4490.1 4528.0 4636.4 4647.3 4648.6 4809.0 4923.6 5007.3 5020.4 5030.4 5056.5 4723.3 4724.8 4725.5 4725.7 4763.3 4710.4 4890.2 4896.2 4916.0 4932.8 4894.1 4919.6 4941.6 4944.3 5000.8 4875.8 4894.3 4900.7 4901.3 4905.7 4833.8 4865.5 4866.0 4876.7 4908.7 4598.3 4687.9 4705.4 4807.7 4840.2 4817.0 4861.0 4887.0 4912.3 4941.5 4785.0 4801.6 4816.3 4818.3 4855.6 05:30:20 INFO - 2015-11-25 05:30:20,802 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,802 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:41266.33 Median:3247.43 stddev:72541.03 (2233.8%) stddev-sans-first:72679.92 05:30:20 INFO - 2015-11-25 05:30:20,803 DEBUG : BROWSER_OUTPUT: Values: 490.3 494.4 558.1 574.7 581.9 21618.4 32732.0 33020.7 33290.3 33292.3 33795.3 34202.7 34207.1 34235.1 34281.1 6319.3 6408.4 6419.9 7827.0 7932.6 9397.7 9557.7 9578.5 9587.3 9595.6 9702.0 9853.7 9854.9 9858.0 9860.2 6668.4 6883.2 9657.8 10114.1 10436.1 9895.8 10052.7 10054.9 10055.4 10062.4 36815.0 37024.3 37262.4 37339.0 37527.6 2566.2 2701.8 2732.6 2745.8 2760.4 27702.2 27994.9 28173.6 28231.4 28342.3 3170.9 3244.2 3250.7 3318.1 3320.4 223422.0 223798.0 224677.0 224790.0 225006.0 27420.3 52662.0 53193.0 53284.0 53296.2 31843.7 31971.0 32019.2 54541.5 55624.2 12167.9 12403.9 12414.6 12420.3 12428.4 9680.9 9862.6 9922.7 9935.6 9935.6 17364.0 17774.2 17810.5 17871.1 17872.9 6302.7 6647.4 6708.2 6710.5 6762.2 809.5 869.7 966.6 999.5 1015.5 25771.0 25983.0 26337.5 26384.0 26439.7 909.2 933.9 972.5 984.5 988.4 12446.9 12507.2 12565.2 12862.1 13136.1 266253.0 272033.9 273510.0 273709.0 274077.0 41772.2 41911.6 42017.6 42087.2 42097.6 722.7 792.3 1124.8 1179.9 1184.1 21323.4 21512.7 22128.6 23164.9 23643.9 25471.9 26289.9 35650.5 35978.6 36006.6 316999.0 325131.0 325848.0 326179.0 327822.0 26838.0 27353.6 27356.6 27553.7 27578.3 33699.0 37252.6 37772.6 38582.4 38638.2 30854.2 31389.7 32189.2 32645.7 32805.7 31720.2 31973.5 32007.8 33936.8 35489.6 31653.7 31938.0 32010.7 32185.7 32277.2 25871.7 26552.6 26787.9 26964.7 27138.3 21039.0 21083.3 21140.8 21176.7 21202.2 05:30:20 INFO - 2015-11-25 05:30:20,803 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,803 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:26494.34 Median:3483.85 stddev:83850.72 (2406.8%) stddev-sans-first:84062.92 05:30:20 INFO - 2015-11-25 05:30:20,803 DEBUG : BROWSER_OUTPUT: Values: 328.3 328.5 334.9 338.3 339.6 11078.2 11452.1 14700.7 15105.3 15114.1 12958.6 13103.0 13107.1 13248.5 13606.5 4764.2 4805.1 4937.3 4939.0 4942.5 3437.7 3530.0 4869.8 5474.0 5617.1 5520.6 5522.4 5541.9 5558.5 5570.1 5035.3 5568.5 5594.8 5597.5 5600.7 4941.7 4946.0 4950.9 4959.6 4960.8 19051.7 19059.4 19397.1 19440.2 19540.3 1808.1 1850.6 1917.6 1971.2 2010.9 12393.3 12605.2 12667.6 12758.1 13114.9 1750.8 1784.5 1786.1 1800.0 1833.2 51030.7 51449.4 52127.0 52964.0 53724.2 9373.5 9598.3 9686.4 9694.5 9700.7 12223.4 12230.1 12258.8 12273.9 12278.3 6804.7 6893.8 6925.6 6954.0 7033.5 6536.6 6564.7 6599.8 6631.1 6648.1 5740.5 5770.2 5788.2 5818.0 5818.1 1411.4 1465.5 1626.8 1661.1 1684.6 848.5 884.1 885.8 971.9 986.8 15912.2 16194.0 16203.1 16608.3 16680.7 760.4 892.7 901.3 903.1 904.7 8834.5 9146.0 9179.9 9185.0 9258.6 506234.0 508269.0 509420.0 524712.0 525804.0 58836.4 61528.7 61983.2 62416.4 62419.7 1053.7 1060.6 1063.0 1065.0 1069.9 13406.5 14148.0 14365.0 14533.5 14645.6 29173.9 30099.4 30327.0 30368.8 32937.8 35417.6 35923.0 36501.6 36563.5 37194.4 8446.9 8459.3 8466.7 8516.9 8832.9 13456.7 13510.7 15846.4 16538.2 16902.4 13001.8 13609.6 13826.6 13848.9 14002.6 13379.2 13758.7 13821.6 13872.0 14161.2 10138.5 10435.4 12227.2 12436.3 13611.0 8657.0 8683.3 8691.6 8695.0 8829.2 11682.5 13352.7 13382.1 13435.4 13922.1 05:30:20 INFO - 2015-11-25 05:30:20,803 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4762.20 Median:5832.80 stddev:2467.36 (42.3%) stddev-sans-first:2457.12 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: Values: 891.3 1572.9 1627.5 1631.0 1666.8 5464.5 6953.6 7137.2 7154.1 7163.1 6921.3 7072.8 7072.9 7087.0 7097.9 1671.6 1706.2 2040.1 2042.3 2065.3 2056.2 2063.7 2067.1 2072.4 2073.3 2034.2 2040.9 2078.4 2082.3 2084.6 2046.0 2069.7 2087.1 2098.5 2099.0 2091.1 2110.9 2111.6 2115.2 2116.3 6906.0 6924.5 6963.5 6970.6 6984.6 3953.2 3979.9 3988.0 4004.0 4026.8 2236.4 2541.8 2653.3 2654.5 2654.9 763.8 768.8 782.1 857.6 889.0 9151.1 9157.1 9178.5 9181.4 9186.7 3821.7 3866.2 3916.1 3972.2 3981.3 8055.1 8064.9 8066.8 8075.2 8080.4 4062.4 4078.6 4078.7 4088.8 4090.1 3712.2 3712.7 3717.1 3719.2 3723.4 2658.3 2798.4 3233.3 3255.7 3388.2 1063.3 1071.3 1071.5 1076.1 1078.3 805.6 806.2 834.1 834.6 981.1 6326.3 6330.5 6393.8 6406.3 6410.6 1010.1 1012.6 1020.0 1027.7 1052.0 5034.6 5120.0 5125.4 5127.1 5128.7 7983.3 8381.6 8382.6 8386.0 8405.9 8212.9 8236.9 8275.1 8284.0 8345.7 6126.1 6216.0 6232.3 6261.7 6307.4 6032.8 6055.7 6086.7 6111.0 6122.2 7267.5 7348.3 7357.3 7453.7 7504.4 7498.8 7585.8 7593.0 7638.2 7669.1 5815.2 5821.6 5826.5 5832.4 5833.2 5083.4 5794.0 6232.6 6234.0 6251.4 6045.8 6117.7 6185.6 6229.2 6231.1 5979.9 6107.1 6115.0 6155.7 6237.1 5838.7 5866.2 5918.1 5919.6 5980.3 5553.1 5553.3 5584.4 5586.1 5593.8 5757.7 5785.5 5795.4 5804.7 5807.9 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4660.39 Median:5302.10 stddev:1373.82 (25.9%) stddev-sans-first:1338.08 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: Values: 297.9 303.4 305.2 310.8 311.6 5279.8 5324.4 5326.6 5342.2 5374.0 5244.9 5252.3 5253.6 5263.8 5276.8 3889.9 4148.6 4149.8 4155.5 4155.6 3994.8 4018.0 4150.1 4157.6 4164.0 4105.2 4110.1 4113.0 4114.7 4119.0 3856.7 3963.8 3992.2 4106.6 4117.9 3637.8 3638.0 3643.5 3643.7 3648.1 5086.3 5094.2 5096.6 5107.7 5112.8 4754.7 4757.3 4759.2 4763.3 4770.1 5452.2 5581.9 5591.5 5595.1 5601.2 3980.4 3981.5 3982.1 3985.9 3987.0 5884.9 5886.1 5892.7 5896.7 5897.8 4105.0 4108.7 4108.8 4110.3 4111.2 3884.3 3976.5 4161.0 4168.1 4170.1 4614.5 4640.1 4647.5 4649.3 4650.2 5599.9 5609.3 5610.1 5616.1 5617.1 1417.8 1420.5 1422.8 1423.2 1423.4 965.6 967.2 977.3 991.1 997.2 4913.7 4928.0 4930.7 4931.5 4932.5 5807.9 5810.3 5813.1 5815.7 5823.7 4917.1 4924.3 4928.2 4928.7 4929.0 3065.7 3101.3 3131.8 3138.1 3138.8 5535.3 5588.2 5592.5 5603.1 5603.1 6030.6 6037.8 6040.9 6041.2 6041.9 5567.4 5578.6 5581.1 5583.6 5590.1 5654.8 5847.1 5847.9 5860.5 5863.1 5745.6 5747.3 5749.2 5752.6 5757.5 5582.5 5583.8 5584.2 5587.2 5588.4 3956.3 3988.6 4007.1 4028.0 4037.6 5824.1 5849.8 5849.9 5851.8 5862.6 5598.7 5636.9 5641.5 5642.0 5647.4 5515.9 5599.9 5623.4 5642.4 5652.9 5511.6 5523.0 5534.7 5536.8 5537.4 5409.9 5592.0 5632.6 5663.4 5672.7 5356.6 5435.6 5461.5 5469.3 5474.2 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12109.68 Median:21412.40 stddev:13960.42 (65.2%) stddev-sans-first:13972.93 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: Values: 621.6 709.7 729.1 754.1 755.6 11286.8 11479.9 11493.5 11495.4 11537.6 11849.5 12079.1 12114.2 12141.0 12148.9 369.6 377.1 773.7 779.4 786.4 770.4 775.5 782.4 782.6 782.9 378.1 813.1 816.9 818.1 818.8 384.1 784.6 796.9 798.4 800.1 587.3 591.3 591.3 591.3 591.5 11451.2 11482.4 11501.4 11501.9 11510.7 10285.3 10464.5 10850.3 10957.8 11333.0 8518.4 8618.7 8803.4 9195.3 9242.2 3241.6 3242.4 3281.9 3298.5 3313.3 37082.6 41702.0 41995.8 42284.8 42398.4 9254.3 9327.3 9373.6 9401.4 9444.4 16435.4 16472.9 16479.2 16500.7 16704.9 6373.6 6473.4 6501.4 6559.8 6816.0 3535.7 3536.7 3609.4 3652.3 3736.3 2647.4 2676.2 2679.8 2680.6 2681.9 454.1 471.5 474.7 492.4 508.1 540.8 549.3 561.3 574.7 589.7 7443.4 7457.0 7470.7 7547.6 7613.4 322.0 426.4 440.2 479.4 509.8 3723.4 3729.6 3733.7 3796.6 3837.3 49824.0 53081.2 54076.7 54254.5 54283.0 38048.6 38500.8 44818.8 45960.8 46270.0 37663.0 38898.2 39360.6 39461.8 39469.6 7039.5 7285.2 7469.6 7526.1 7613.4 30664.9 31125.7 31203.4 31377.9 31633.0 37824.8 38326.0 40480.8 40653.4 40778.0 9199.5 9439.5 9451.4 9784.7 9787.3 10455.2 10707.9 10824.0 10985.9 11100.8 10178.2 10266.6 10302.7 10434.1 10725.5 10016.3 10034.5 10073.5 10399.3 10419.1 9789.4 10005.6 10046.1 10231.3 10273.2 7986.2 8119.6 8196.2 9108.2 9260.4 8220.9 8701.2 8703.4 9126.2 9216.7 05:30:20 INFO - 2015-11-25 05:30:20,804 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:30:20 INFO - 2015-11-25 05:30:20,805 DEBUG : BROWSER_OUTPUT: 05:30:21 INFO - 2015-11-25 05:30:21,477 INFO : Browser exited with error code: 0 05:30:21 INFO - 2015-11-25 05:30:21,499 INFO : Completed test dromaeo_css (00:18:35) 05:30:21 INFO - 2015-11-25 05:30:21,499 INFO : Starting test kraken 05:30:21 INFO - 2015-11-25 05:30:21,499 DEBUG : operating with platform_type : linux_ 05:30:21 INFO - 2015-11-25 05:30:21,499 INFO : Initialising browser for kraken test... 05:30:21 INFO - 2015-11-25 05:30:21,505 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_H_wgS/profile http://localhost:58440/getInfo.html 05:30:25 INFO - 2015-11-25 05:30:25,037 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:30:25 INFO - 2015-11-25 05:30:25,037 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:30:25 INFO - 2015-11-25 05:30:25,037 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:30:25 INFO - 2015-11-25 05:30:25,037 DEBUG : BROWSER_OUTPUT: __metrics 05:30:25 INFO - 2015-11-25 05:30:25,154 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:30:26 INFO - 2015-11-25 05:30:26,276 INFO : Browser initialized. 05:30:26 INFO - 2015-11-25 05:30:26,276 INFO : Running cycle 1/1 for kraken test... 05:30:26 INFO - 2015-11-25 05:30:26,276 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_H_wgS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:30:38 INFO - 2015-11-25 05:30:38,182 DEBUG : BROWSER_OUTPUT: RSS: Main: 139001856 05:30:38 INFO - 2015-11-25 05:30:38,182 DEBUG : BROWSER_OUTPUT: 05:30:40 INFO - 2015-11-25 05:30:40,757 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58440/tests/kraken/driver.html?testName=audio-beat-detection) 05:30:41 INFO - 2015-11-25 05:30:41,346 DEBUG : BROWSER_OUTPUT: RSS: Main: 179552256 05:30:41 INFO - 2015-11-25 05:30:41,346 DEBUG : BROWSER_OUTPUT: 05:30:44 INFO - 2015-11-25 05:30:44,022 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58440/tests/kraken/driver.html?testName=audio-dft) 05:30:44 INFO - 2015-11-25 05:30:44,603 DEBUG : BROWSER_OUTPUT: RSS: Main: 166748160 05:30:44 INFO - 2015-11-25 05:30:44,603 DEBUG : BROWSER_OUTPUT: 05:30:47 INFO - 2015-11-25 05:30:47,662 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58440/tests/kraken/driver.html?testName=audio-fft) 05:30:48 INFO - 2015-11-25 05:30:48,223 DEBUG : BROWSER_OUTPUT: RSS: Main: 148799488 05:30:48 INFO - 2015-11-25 05:30:48,223 DEBUG : BROWSER_OUTPUT: 05:30:50 INFO - 2015-11-25 05:30:50,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58440/tests/kraken/driver.html?testName=audio-oscillator) 05:30:50 INFO - 2015-11-25 05:30:50,808 DEBUG : BROWSER_OUTPUT: RSS: Main: 153063424 05:30:50 INFO - 2015-11-25 05:30:50,809 DEBUG : BROWSER_OUTPUT: 05:30:53 INFO - 2015-11-25 05:30:53,082 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58440/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:53 INFO - 2015-11-25 05:30:53,644 DEBUG : BROWSER_OUTPUT: RSS: Main: 155246592 05:30:53 INFO - 2015-11-25 05:30:53,644 DEBUG : BROWSER_OUTPUT: 05:30:56 INFO - 2015-11-25 05:30:56,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58440/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:57 INFO - 2015-11-25 05:30:57,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 153595904 05:30:57 INFO - 2015-11-25 05:30:57,196 DEBUG : BROWSER_OUTPUT: 05:31:00 INFO - 2015-11-25 05:31:00,584 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58440/tests/kraken/driver.html?testName=imaging-desaturate) 05:31:01 INFO - 2015-11-25 05:31:01,165 DEBUG : BROWSER_OUTPUT: RSS: Main: 154710016 05:31:01 INFO - 2015-11-25 05:31:01,165 DEBUG : BROWSER_OUTPUT: 05:31:03 INFO - 2015-11-25 05:31:03,994 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58440/tests/kraken/driver.html?testName=json-parse-financial) 05:31:04 INFO - 2015-11-25 05:31:04,576 DEBUG : BROWSER_OUTPUT: RSS: Main: 155578368 05:31:04 INFO - 2015-11-25 05:31:04,576 DEBUG : BROWSER_OUTPUT: 05:31:06 INFO - 2015-11-25 05:31:06,324 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58440/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:31:06 INFO - 2015-11-25 05:31:06,885 DEBUG : BROWSER_OUTPUT: RSS: Main: 166436864 05:31:06 INFO - 2015-11-25 05:31:06,886 DEBUG : BROWSER_OUTPUT: 05:31:08 INFO - 2015-11-25 05:31:08,777 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:31:09 INFO - 2015-11-25 05:31:09,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 164265984 05:31:09 INFO - 2015-11-25 05:31:09,372 DEBUG : BROWSER_OUTPUT: 05:31:11 INFO - 2015-11-25 05:31:11,524 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:12 INFO - 2015-11-25 05:31:12,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 172584960 05:31:12 INFO - 2015-11-25 05:31:12,094 DEBUG : BROWSER_OUTPUT: 05:31:14 INFO - 2015-11-25 05:31:14,615 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:15 INFO - 2015-11-25 05:31:15,201 DEBUG : BROWSER_OUTPUT: RSS: Main: 179933184 05:31:15 INFO - 2015-11-25 05:31:15,201 DEBUG : BROWSER_OUTPUT: 05:31:17 INFO - 2015-11-25 05:31:17,824 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:18 INFO - 2015-11-25 05:31:18,405 DEBUG : BROWSER_OUTPUT: RSS: Main: 177250304 05:31:18 INFO - 2015-11-25 05:31:18,405 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58440/tests/kraken/driver.html?testName=ai-astar) 05:31:20 INFO - 2015-11-25 05:31:20,425 DEBUG : BROWSER_OUTPUT: RSS: Main: 236724224 05:31:20 INFO - 2015-11-25 05:31:20,425 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,426 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |0;ai-astar;112;114;105;112;112;112;102;111;111;105 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;130;155;162;136;154;156;131;171;130 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |2;audio-dft;163;162;165;164;163;164;164;165;164;166 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;85;90;73;72;71;107;89;71 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;95;135;95;95;95;140;94;95;136 05:31:20 INFO - 2015-11-25 05:31:20,427 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;110;109;110;109;110;110;110 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;146;147;146;147;146;146;147;147 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;106;95;95;94;95 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;70;72;72;71;71;69;72;70;70;70 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;68;68;69;68;69;70;68;70 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;82;86;88;86;85;86;86;84;84;83 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;121;140;136;123;121;120;128;129;122;127 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;139;156;144;145;145;157;143;148;143 05:31:20 INFO - 2015-11-25 05:31:20,428 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;69;70;67;68;69;69;70;67;68;68 05:31:20 INFO - 2015-11-25 05:31:20,429 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:31:20 INFO - 2015-11-25 05:31:20,429 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:31:20 INFO - 2015-11-25 05:31:20,429 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,966 05:31:20 INFO - 2015-11-25 05:31:20,429 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:31:20 INFO - 2015-11-25 05:31:20,429 DEBUG : BROWSER_OUTPUT: __startTimestamp1448458280425__endTimestamp 05:31:20 INFO - 2015-11-25 05:31:20,429 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.60 Median:112.00 stddev:4.03 (3.6%) stddev-sans-first:4.18 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: Values: 112.0 114.0 105.0 112.0 112.0 112.0 102.0 111.0 111.0 105.0 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:147.70 Median:154.50 stddev:14.80 (9.6%) stddev-sans-first:15.61 05:31:20 INFO - 2015-11-25 05:31:20,437 DEBUG : BROWSER_OUTPUT: Values: 152.0 130.0 155.0 162.0 136.0 154.0 156.0 131.0 171.0 130.0 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.00 Median:164.00 stddev:1.15 (0.7%) stddev-sans-first:1.17 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: Values: 163.0 162.0 165.0 164.0 163.0 164.0 164.0 165.0 164.0 166.0 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.10 Median:72.50 stddev:12.27 (16.9%) stddev-sans-first:12.66 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 85.0 90.0 73.0 72.0 71.0 107.0 89.0 71.0 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.50 Median:95.00 stddev:20.40 (21.5%) stddev-sans-first:21.13 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 135.0 95.0 95.0 95.0 140.0 94.0 95.0 136.0 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,438 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 110.0 109.0 110.0 109.0 110.0 110.0 110.0 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.71 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 146.0 147.0 146.0 147.0 146.0 146.0 147.0 147.0 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.10 Median:95.00 stddev:3.51 (3.7%) stddev-sans-first:3.70 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 106.0 95.0 95.0 94.0 95.0 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:70.70 Median:71.00 stddev:1.06 (1.5%) stddev-sans-first:1.09 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: Values: 70.0 72.0 72.0 71.0 71.0 69.0 72.0 70.0 70.0 70.0 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.50 stddev:0.84 (1.2%) stddev-sans-first:0.87 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 68.0 68.0 69.0 68.0 69.0 70.0 68.0 70.0 05:31:20 INFO - 2015-11-25 05:31:20,439 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.00 stddev:1.76 (2.1%) stddev-sans-first:1.50 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: Values: 82.0 86.0 88.0 86.0 85.0 86.0 86.0 84.0 84.0 83.0 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:127.50 stddev:6.80 (5.3%) stddev-sans-first:6.89 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: Values: 121.0 140.0 136.0 123.0 121.0 120.0 128.0 129.0 122.0 127.0 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.70 Median:145.00 stddev:6.48 (4.5%) stddev-sans-first:6.06 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: Values: 137.0 139.0 156.0 144.0 145.0 145.0 157.0 143.0 148.0 143.0 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 67.0 68.0 69.0 69.0 70.0 67.0 68.0 68.0 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:31:20 INFO - 2015-11-25 05:31:20,440 DEBUG : BROWSER_OUTPUT: 05:31:21 INFO - 2015-11-25 05:31:21,414 INFO : Browser exited with error code: 0 05:31:21 INFO - 2015-11-25 05:31:21,463 INFO : Completed test kraken (00:00:59) 05:31:21 INFO - 2015-11-25 05:31:21,463 INFO : Starting test v8_7 05:31:21 INFO - 2015-11-25 05:31:21,463 DEBUG : operating with platform_type : linux_ 05:31:21 INFO - 2015-11-25 05:31:21,463 INFO : Initialising browser for v8_7 test... 05:31:21 INFO - 2015-11-25 05:31:21,469 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7YgmKP/profile http://localhost:58440/getInfo.html 05:31:24 INFO - 2015-11-25 05:31:24,904 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:31:24 INFO - 2015-11-25 05:31:24,904 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:31:24 INFO - 2015-11-25 05:31:24,904 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:31:24 INFO - 2015-11-25 05:31:24,904 DEBUG : BROWSER_OUTPUT: __metrics 05:31:24 INFO - 2015-11-25 05:31:24,988 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:31:26 INFO - 2015-11-25 05:31:26,039 INFO : Browser initialized. 05:31:26 INFO - 2015-11-25 05:31:26,039 INFO : Running cycle 1/1 for v8_7 test... 05:31:26 INFO - 2015-11-25 05:31:26,039 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7YgmKP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:31:37 INFO - 2015-11-25 05:31:37,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 138571776 05:31:37 INFO - 2015-11-25 05:31:37,956 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,421 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:58440/tests/v8_7/run.html (next: http://localhost:58440/tests/v8_7/run.html) 05:31:59 INFO - 2015-11-25 05:31:59,667 DEBUG : BROWSER_OUTPUT: RSS: Main: 453238784 05:31:59 INFO - 2015-11-25 05:31:59,667 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,667 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: |0;Richards;165.0982334489021 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;192.0860545524395 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.39251040221916 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 05:31:59 INFO - 2015-11-25 05:31:59,668 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1994.0239043824702 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: |5;Earley;795.7074721780604 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: |6;Boyer;12156.626506024097 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: |7;RegExp;26921.052631578947 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: |8;Splay;694.4444444444445 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:31:59 INFO - 2015-11-25 05:31:59,669 DEBUG : BROWSER_OUTPUT: __startTimestamp1448458319666__endTimestamp 05:31:59 INFO - 2015-11-25 05:31:59,670 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,670 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:31:59 INFO - 2015-11-25 05:31:59,670 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:31:59 INFO - 2015-11-25 05:31:59,670 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,672 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.10 Median:165.10 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,672 DEBUG : BROWSER_OUTPUT: Values: 165.1 05:31:59 INFO - 2015-11-25 05:31:59,672 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,672 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:192.09 Median:192.09 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,672 DEBUG : BROWSER_OUTPUT: Values: 192.1 05:31:59 INFO - 2015-11-25 05:31:59,672 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,673 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.39 Median:277.39 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,673 DEBUG : BROWSER_OUTPUT: Values: 277.4 05:31:59 INFO - 2015-11-25 05:31:59,673 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,673 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,673 DEBUG : BROWSER_OUTPUT: Values: 5138.5 05:31:59 INFO - 2015-11-25 05:31:59,673 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1994.02 Median:1994.02 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: Values: 1994.0 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:795.71 Median:795.71 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: Values: 795.7 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,674 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12156.63 Median:12156.63 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: Values: 12156.6 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26921.05 Median:26921.05 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: Values: 26921.1 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:694.44 Median:694.44 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,675 DEBUG : BROWSER_OUTPUT: Values: 694.4 05:31:59 INFO - 2015-11-25 05:31:59,676 DEBUG : BROWSER_OUTPUT: 05:31:59 INFO - 2015-11-25 05:31:59,676 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 05:31:59 INFO - 2015-11-25 05:31:59,676 DEBUG : BROWSER_OUTPUT: Values: 4826.9 05:31:59 INFO - 2015-11-25 05:31:59,676 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:31:59 INFO - 2015-11-25 05:31:59,676 DEBUG : BROWSER_OUTPUT: 05:32:00 INFO - 2015-11-25 05:32:00,603 INFO : Browser exited with error code: 0 05:32:00 INFO - 2015-11-25 05:32:00,617 INFO : Completed test v8_7 (00:00:39) 05:32:00 INFO - 2015-11-25 05:32:00,656 INFO : Completed test suite (00:20:14) 05:32:00 INFO - 2015-11-25 05:32:00,657 DEBUG : Working with test: dromaeo_css 05:32:00 INFO - 2015-11-25 05:32:00,657 DEBUG : Generating results file: dromaeo_css 05:32:00 INFO - 2015-11-25 05:32:00,658 DEBUG : Working with test: kraken 05:32:00 INFO - 2015-11-25 05:32:00,658 DEBUG : Generating results file: kraken 05:32:00 INFO - 2015-11-25 05:32:00,658 INFO : javascript benchmark 05:32:00 INFO - 2015-11-25 05:32:00,658 DEBUG : Working with test: v8_7 05:32:00 INFO - 2015-11-25 05:32:00,658 DEBUG : Generating results file: v8_7 05:32:00 INFO - 2015-11-25 05:32:00,659 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:32:00 INFO - 2015-11-25 05:32:00,710 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,35]] 05:32:00 INFO - 2015-11-25 05:32:00,710 DEBUG : process_Request line: dromaeo_css 6167.64 graph.html#tests=[[72,94,35]] 05:32:00 INFO - 2015-11-25 05:32:00,710 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:32:00 INFO - 2015-11-25 05:32:00,750 DEBUG : process_Request line: kraken 1526.10 graph.html#tests=[[232,94,35]] 05:32:00 INFO - 2015-11-25 05:32:00,750 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:32:00 INFO - 2015-11-25 05:32:00,779 DEBUG : process_Request line: v8_7 19491.90 graph.html#tests=[[230,94,35]] 05:32:00 INFO - 2015-11-25 05:32:00,780 INFO : javascript benchmark 05:32:00 INFO - 2015-11-25 05:32:00,783 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2642.286582622691, 2711.4170453352535, 2725.6320396746432, 2733.4396448728, 2747.4917140329035, 4464.39730636364, 4593.931091033629, 4604.769761511923, 4610.07798440312, 4633.142868569313, 4550.863474095782, 4575.862724118273, 4644.047970166116, 4646.976765116178, 4715.03341814928, 4299.204647140274, 4303.763293018883, 4309.82760689572, 4348.826046958118, 4356.825726970933, 4150.3359462486005, 4397.428334316534, 4402.823887044515, 4438.400815889846, 4453.710508816933, 4139.91720165595, 4210.431591735107, 4255.787210639471, 4260.147970405931, 4267.551907049746, 4285.57144285572, 4287.699861009731, 4294.098239369736, 4332.501762297345, 4340.587644173798, 4115.938260926088, 4277.486701595797, 4296.9140617187795, 4310.935335969963, 4345.217860785057, 4474.597286244221, 4481.3726078349055, 4492.07912377965, 4603.654725895572, 4632.976835115836, 4953.504649535053, 4977.975110124463, 4981.750912454399, 4986.52628000343, 5034.119029169918, 4820.325154478376, 5009.373828271466, 5009.649324547283, 5010.448850626428, 5021.799128034874, 4569.794359253809, 4591.586757191869, 4600.631949444037, 4658.254679251371, 4735.029318989599, 5124.5131712487255, 5139.486051394831, 5141.948580514222, 5145.151050076695, 5145.588352931756, 4679.415073115861, 4935.827246046371, 4942.678725882806, 4945.0, 4975.4527002029745, 4860.513948605182, 4914.926276105862, 4946.901061978812, 4975.825846095442, 4989.90020199594, 4912.1649319615035, 4929.728864912464, 4933.309336692866, 4945.802167913315, 4988.45127036033, 4908.509149085063, 4909.116359055333, 4914.557689807934, 4917.0903382874285, 4968.701877887338, 4025.0742329264435, 4065.593440655911, 4110.321796903478, 4113.938290925638, 4144.70987030911, 3646.3072016316814, 3650.990099009907, 3657.0, 3672.210474747942, 3682.576503702055, 4293.377460268306, 4296.334068219431, 4304.418903448057, 4336.804843782037, 4340.348947657813, 4785.0, 4906.435759887659, 4908.214685650279, 4934.309196712463, 4949.48030456804, 4294.463192100988, 4296.0548679290505, 4310.439642846472, 4321.070969741458, 4329.826806927687, 4442.844500442469, 4454.888627784331, 4457.910841783211, 4458.286674132188, 4490.057088011489, 4528.049109686936, 4636.420447444069, 4647.302904564207, 4648.581627653527, 4809.0, 4923.606111510999, 5007.273945277987, 5020.422651395136, 5030.37120359955, 5056.494350565061, 4723.315119307613, 4724.811007559659, 4725.527447255247, 4725.692829966041, 4763.261694437368, 4710.364100846342, 4890.217565189552, 4896.1921282987905, 4916.041371932439, 4932.802687892444, 4894.094592500399, 4919.557239848431, 4941.579965702956, 4944.307796908508, 5000.849974500771, 4875.780589873535, 4894.314795928502, 4900.705957642553, 4901.289313049517, 4905.656604037683, 4833.758312084452, 4865.464798872051, 4865.975670121768, 4876.683015603974, 4908.705477671351, 4598.287265473857, 4687.9296810547185, 4705.364775755366, 4807.735574543433, 4840.201366774443, 4816.95183048165, 4861.027794441055, 4886.951130488792, 4912.312276281395, 4941.530554597308, 4785.0, 4801.567858892717, 4816.301636262792, 4818.277258411266, 4855.562999330077], "unit": "ms", "name": "dojo.html", "value": 4563.00772484426}, {"lowerIsBetter": false, "replicates": [490.34538890581075, 494.40424288732083, 558.1272567856772, 574.6552068758743, 581.9010768169412, 21618.351449456502, 32732.0, 33020.66979330209, 33290.33419331612, 33292.33415331692, 33795.32409351812, 34202.65797342026, 34207.14482137948, 34235.14412139698, 34281.142971425725, 6319.273283572379, 6408.391202835724, 6419.87160256797, 7827.0, 7932.603369831514, 9397.671081512148, 9557.713268601952, 9578.473183974878, 9587.328886977913, 9595.568199431042, 9701.951490242538, 9853.704388868346, 9854.90145098547, 9857.95071024644, 9860.161886239679, 6668.433183179435, 6883.208431030445, 9657.75855603612, 10114.089731924161, 10436.060754532053, 9895.802083958353, 10052.69841904744, 10054.949725251327, 10055.446950417723, 10062.39625622465, 36815.0, 37024.25951480982, 37262.441063384074, 37339.0, 37527.624723752684, 2566.1659960512907, 2701.797365197618, 2732.5764506501523, 2745.8489783061923, 2760.4479104179245, 27702.168934931986, 27994.86002569974, 28173.57739633907, 28231.435371292664, 28342.29144271389, 3170.889018884328, 3244.156519304974, 3250.7074363307415, 3318.0543545089777, 3320.352531256406, 223421.99999999837, 223798.0, 224677.0, 224790.0, 225006.0, 27420.314492137855, 52662.0, 53193.0, 53284.0, 53296.20055699167, 31843.681563184535, 31971.0, 32019.199520012186, 54541.454585454434, 55624.16563751547, 12167.878321216851, 12403.875961240452, 12414.62756117318, 12420.316882571364, 12428.440720167615, 9680.903190968, 9862.556184971694, 9922.652707155215, 9935.602575896883, 9935.602575897028, 17364.0, 17774.20016099253, 17810.465686029438, 17871.106444677713, 17872.91063544674, 6302.747890084437, 6647.368499992493, 6708.161479815023, 6710.530262881649, 6762.154730658668, 809.4859764049871, 869.6564856881496, 966.5505539923829, 999.480270259461, 1015.5277795824977, 25771.0, 25983.0, 26337.47325053488, 26384.0, 26439.735602643726, 909.1544863277215, 933.9112784285481, 972.5040229483014, 984.542187882638, 988.4365911430416, 12446.937765311115, 12507.187032842836, 12565.246085234916, 12862.099653024386, 13136.080474366894, 266253.0, 272033.9194912078, 273510.0, 273709.0, 274077.0, 41772.164556708696, 41911.580884191375, 42017.57982420198, 42087.15825683469, 42097.579024209976, 722.6892436252357, 792.3146478296233, 1124.791913496006, 1179.9056075513768, 1184.0705046538426, 21323.36029919105, 21512.67730984005, 22128.557428851655, 23164.884175579013, 23643.881780590986, 25471.872640636677, 26289.868550657124, 35650.46524302189, 35978.64021359858, 36006.63993360033, 316999.0, 325131.0, 325848.0, 326179.0, 327822.0, 26838.0, 27353.589696154973, 27356.58965115485, 27553.724462755115, 27578.310542236606, 33699.0, 37252.627473726, 37772.62227377691, 38582.4212636805, 38638.2272354557, 30854.228644284074, 31389.686103138676, 32189.195270118435, 32645.673543264264, 32805.671943280264, 31720.206994825316, 31973.520397194527, 32007.839960800047, 33936.83031584826, 35489.64510354863, 31653.683463165074, 31938.0, 32010.67989320077, 32185.67814321827, 32277.193070173435, 25871.741282586932, 26552.601710974737, 26787.86606066957, 26964.730352697006, 27138.286171382704, 21038.99468843112, 21083.262085826922, 21140.78859211388, 21176.682349765073, 21202.15191392327], "unit": "ms", "name": "ext.html", "value": 16118.88097227947}, {"lowerIsBetter": false, "replicates": [328.28925376559744, 328.54988665528225, 334.9145967778216, 338.25921232500804, 339.57722635319016, 11078.169137314713, 11452.141089418306, 14700.705985880277, 15105.320260588287, 15114.093154410742, 12958.611241662742, 13103.0, 13107.148035377717, 13248.47006119754, 13606.523771667993, 4764.166270902596, 4805.087033463648, 4937.308776771255, 4938.950610493885, 4942.456329803718, 3437.7077948374417, 3529.982350088259, 4869.829555965542, 5473.972630136864, 5617.129344951519, 5520.558355331564, 5522.3649280332875, 5541.944580554204, 5558.499735023847, 5570.136628822539, 5035.269885866547, 5568.49883510484, 5594.832155035356, 5597.524210442118, 5600.719964001783, 4941.703497790144, 4945.975270123627, 4950.8762280943265, 4959.603231741452, 4960.826371076995, 19051.714224286647, 19059.42821715351, 19397.127129279073, 19440.22239110434, 19540.31608893682, 1808.1230603157458, 1850.5651171974575, 1917.6452356314126, 1971.1918113573365, 2010.88940108294, 12393.256404615662, 12605.180663256859, 12667.556635517712, 12758.106932514727, 13114.934425327907, 1750.8249175082517, 1784.50925995351, 1786.0623172834237, 1800.0459756329167, 1833.1933949062504, 51030.744846275906, 51449.416196983104, 52127.0, 52964.0, 53724.194137088365, 9373.531323433801, 9598.32811703188, 9686.370385924893, 9694.51527423626, 9700.708978730649, 12223.449944752507, 12230.082743794073, 12258.754824903452, 12273.877261227452, 12278.263304201777, 6804.727810887608, 6893.827654308682, 6925.58446493212, 6953.9916712076465, 7033.507654464139, 6536.640484773383, 6564.671766411756, 6599.769008084694, 6631.071649969008, 6648.069270302161, 5740.512056475163, 5770.2498675171955, 5788.18965344852, 5817.970910145506, 5818.0981947798155, 1411.3789932429697, 1465.5456808389433, 1626.7966504186977, 1661.1279078483892, 1684.5704345391846, 848.4866655673352, 884.1114679746813, 885.8051228729669, 971.9211675040699, 986.7681094942683, 15912.204389780465, 16194.02835829854, 16203.108829014282, 16608.2526286315, 16680.74978875318, 760.3574979142616, 892.6875593542338, 901.3285102598475, 903.11945852793, 904.6697955246287, 8834.469931804113, 9145.954270228607, 9179.908200918038, 9185.0, 9258.583363748512, 506234.0, 508269.0, 509420.0, 524712.0, 525804.0, 58836.41163588395, 61528.69235653793, 61983.21075551611, 62416.37583624106, 62419.68790156111, 1053.7365658585354, 1060.6022741471947, 1062.984055239172, 1065.0148612533376, 1069.8983596558314, 13406.530771422953, 14148.0, 14365.0, 14533.491327803898, 14645.560633181021, 29173.85413072921, 30099.398012040074, 30327.0, 30368.84815575908, 32937.83531082329, 35417.645823542465, 35923.0, 36501.63498365089, 36563.45154822733, 37194.44208336823, 8446.915530844859, 8459.280961118375, 8466.74599762008, 8516.91483085186, 8832.911670883466, 13456.663583410493, 13510.662233444242, 15846.366145354057, 16538.173091345627, 16902.40841570539, 13001.804972925604, 13609.591712248648, 13826.585202443943, 13848.930755346159, 14002.649933751738, 13379.197248165141, 13758.65603359924, 13821.585352439442, 13872.0, 14161.150330979763, 10138.49307534635, 10435.37387756737, 12227.205231659913, 12436.253824770181, 13611.0, 8656.96116466028, 8683.348748843735, 8691.565421729014, 8695.0, 8829.16122968317, 11682.47428865686, 13352.732945340844, 13382.13016153947, 13435.395407206504, 13922.09506382082], "unit": "ms", "name": "jquery.html", "value": 8230.44387868024}, {"lowerIsBetter": false, "replicates": [891.3448615267781, 1572.897761645493, 1627.4873414874314, 1630.951071467856, 1666.8416500432463, 5464.508194262516, 6953.582785032895, 7137.179224389197, 7154.105736782909, 7163.068801055869, 6921.273266307041, 7072.752453664123, 7072.929270707292, 7087.0, 7097.929020709791, 1671.607172314505, 1706.2066139245248, 2040.081963116596, 2042.305616090529, 2065.2874758208395, 2056.249468943832, 2063.6904464330396, 2067.080149333547, 2072.399004288762, 2073.3365323096614, 2034.2269937423832, 2040.8673436226597, 2078.3557097299886, 2082.3232449453913, 2084.551821358408, 2045.9897700511551, 2069.720587720661, 2087.0608226298073, 2098.4544018555207, 2098.9580208395823, 2091.1426315210692, 2110.873347599149, 2111.630464668692, 2115.2279418012467, 2116.291042500764, 6906.0, 6924.515283930127, 6963.547369420971, 6970.616616086113, 6984.615846128461, 3953.2488827122756, 3979.9403008954887, 3988.0428697112484, 4003.959960400417, 4026.798660067014, 2236.384994126612, 2541.8220724549287, 2653.3499292673228, 2654.482375936693, 2654.9070782522713, 763.7975936376864, 768.8385439057805, 782.0741155055712, 857.5840717252128, 888.9688860889838, 9151.084891510796, 9157.130072643089, 9178.541072946393, 9181.403208791475, 9186.678466253648, 3821.6560509554274, 3866.1881004988704, 3916.099297161669, 3972.2254160439015, 3981.2634662587516, 8055.113937466874, 8064.879026814602, 8066.838663226703, 8075.152109028585, 8080.4343695940715, 4062.370332598452, 4078.57174996627, 4078.6737061035046, 4088.8364465421646, 4090.079732060322, 3712.1647629283193, 3712.7215458840697, 3717.126475278307, 3719.2189640175866, 3723.3670276053135, 2658.282263788805, 2798.398344355973, 3233.272513684449, 3255.706986371238, 3388.2376465295606, 1063.2875973098044, 1071.2768881005445, 1071.5285274479204, 1076.1229597877705, 1078.2775540387959, 805.5931754463958, 806.2421323955463, 834.0533494483683, 834.5660256666499, 981.1415011864618, 6326.304106548276, 6330.493560515148, 6393.7762178324465, 6406.327335629785, 6410.583312084793, 1010.0858722855827, 1012.5645972232011, 1019.9643012494527, 1027.7276521721749, 1051.9684409467727, 5034.647574669812, 5119.974400127976, 5125.359330083739, 5127.102757017507, 5128.743562821844, 7983.281504664608, 8381.58092095405, 8382.622781974826, 8386.0, 8405.915940840514, 8212.876806847902, 8236.917630823615, 8275.131111233355, 8284.0, 8345.749627511184, 6126.08108783686, 6216.036514340284, 6232.345603711636, 6261.655608941461, 6307.400796924285, 6032.819015429544, 6055.666938318434, 6086.6652334122045, 6111.0, 6122.173506576555, 7267.527610705287, 7348.338649521569, 7357.264273572814, 7453.701851926079, 7504.399648028254, 7498.812529686801, 7585.810354741176, 7593.0, 7638.236176382317, 7669.118051424158, 5815.244018277597, 5821.621594596338, 5826.475617194473, 5832.358440571449, 5833.241678581757, 5083.364579427572, 5793.971030144822, 6232.594881332699, 6234.0337247726675, 6251.437370636664, 6045.758169673164, 6117.663528505975, 6185.628862268279, 6229.190205273286, 6231.1276421300145, 5979.850503737442, 6107.083937409424, 6114.991026480759, 6155.72299246526, 6237.064440333987, 5838.678872662044, 5866.2373891393845, 5918.082697181943, 5919.615225010532, 5980.3421623622235, 5553.055980483408, 5553.305836770403, 5584.4136365681015, 5586.078297080936, 5593.804216852391, 5757.683327416865, 5785.537157027344, 5795.39148389413, 5804.65172089676, 5807.8548036299435], "unit": "ms", "name": "mootools.html", "value": 3913.543673352531}, {"lowerIsBetter": false, "replicates": [297.93743313904076, 303.36596513287225, 305.22472918786286, 310.78866370867803, 311.64161214603206, 5279.8416047518585, 5324.3610766708, 5326.627136100475, 5342.2253773202865, 5374.032674118657, 5244.947550524494, 5252.343457067866, 5253.634055145661, 5263.76313065912, 5276.841694749154, 3889.883303500885, 4148.6266236038755, 4149.792510374469, 4155.54555905433, 4155.646770024537, 3994.840206391741, 4017.959820401788, 4150.149219410029, 4157.605027522381, 4163.958360416387, 4105.178964207155, 4110.095778928646, 4113.03343714227, 4114.691398145151, 4118.979405102985, 3856.730028897978, 3963.7819919904396, 3992.2414741199077, 4106.568810274923, 4117.9176416471655, 3637.7635453695425, 3637.999550123711, 3643.526341575605, 3643.6538528839983, 3648.106213977566, 5086.31334769805, 5094.2358646203365, 5096.592272618182, 5107.744612769384, 5112.846614601568, 4754.7147171169845, 4757.26262429324, 4759.190937540592, 4763.333133361332, 4770.093682200406, 5452.236686863842, 5581.944180558183, 5591.524720398771, 5595.132754423071, 5601.215829783833, 3980.44273801668, 3981.542122655903, 3982.143839074584, 3985.940210896839, 3986.960130398688, 5884.882302353972, 5886.058230683113, 5892.675902825343, 5896.734646940898, 5897.76408943646, 4104.979475102606, 4108.67130629549, 4108.815103320358, 4110.280700877334, 4111.177764447063, 3884.261990221849, 3976.4830572025453, 4160.979195104005, 4168.103857670555, 4170.124273902515, 4614.538546145426, 4640.095181439587, 4647.512011238839, 4649.279361698942, 4650.209464390994, 5599.944000559943, 5609.298837645294, 5610.102383618601, 5616.129499927436, 5617.101263797855, 1417.7873319002233, 1420.4815242436437, 1422.7936949142315, 1423.1887823940463, 1423.4163992762917, 965.6475386483883, 967.2117224462041, 977.3061126600194, 991.0881988570533, 997.2371136080903, 4913.65604407688, 4928.039032388721, 4930.704157750546, 4931.50684931504, 4932.53140951609, 5807.883842323215, 5810.302763668303, 5813.069908814569, 5815.73829177688, 5823.70881455934, 4917.1149193145575, 4924.285978533093, 4928.162212423897, 4928.704277743347, 4929.0, 3065.739412149948, 3101.255698632312, 3131.827749473801, 3138.0585824252635, 3138.8116712997294, 5535.308086489189, 5588.189712491747, 5592.468715472024, 5603.075492543685, 5603.075492543685, 6030.5778595497895, 6037.758489660365, 6040.909386359208, 6041.214642096499, 6041.879162416727, 5567.387587365387, 5578.581606379456, 5581.134924086773, 5583.581231407661, 5590.077637189981, 5654.840757644591, 5847.093700476433, 5847.853803654943, 5860.531157507304, 5863.061910094364, 5745.597808153389, 5747.281589801275, 5749.195112684311, 5752.590615299278, 5757.539396848326, 5582.49757521825, 5583.804566840141, 5584.218209450599, 5587.161925711176, 5588.357338905916, 3956.2680904032845, 3988.6011398859882, 4007.0583412897363, 4027.979860100798, 4037.6366127048705, 5824.126381042877, 5849.824505264849, 5849.853753656193, 5851.824445266649, 5862.618929769551, 5598.720063996702, 5636.915446268391, 5641.464060914206, 5642.040853054908, 5647.435256474319, 5515.889682206413, 5599.8880022400135, 5623.381428042994, 5642.351129620146, 5652.915206271991, 5511.559075274049, 5523.005858945428, 5534.695591742493, 5536.806211782568, 5537.390887002507, 5409.86475338104, 5592.049351610155, 5632.5775566831835, 5663.3770285267765, 5672.744726487401, 5356.5982551308025, 5435.646682965595, 5461.481159289861, 5469.288992430958, 5474.206240095244], "unit": "ms", "name": "prototype.html", "value": 4218.481133636414}, {"lowerIsBetter": false, "replicates": [621.6456619726755, 709.6664567653202, 729.0777166883892, 754.1026178847175, 755.576876948909, 11286.774264514705, 11479.942600286997, 11493.482793274312, 11495.42522873855, 11537.596184133557, 11849.46677399517, 12079.094067944918, 12114.152009359348, 12141.0, 12148.939255303732, 369.5879094809295, 377.11378261086503, 773.7214602743009, 779.3648176735942, 786.4376970466116, 770.3529035610086, 775.5230533222056, 782.37800948246, 782.5852298281919, 782.8590853646341, 378.08503421719365, 813.1177672225642, 816.873384625381, 818.1286929420148, 818.8321394114192, 384.1471932310267, 784.6116172494594, 796.8884356190138, 798.3892322373389, 800.0719165767681, 587.3040447070235, 591.2727345365225, 591.2845456997039, 591.328841764596, 591.4735885062298, 11451.198416110876, 11482.425878706032, 11501.42492875353, 11501.884981150166, 11510.712232194179, 10285.280030397875, 10464.476776161238, 10850.294730842548, 10957.83563246552, 11333.0, 8518.40371174018, 8618.698345557876, 8803.383763136582, 9195.310351723647, 9242.21441177494, 3241.6272128705027, 3242.400155971129, 3281.8523166457567, 3298.472244440902, 3313.2710803623163, 37082.629173707915, 41702.0, 41995.7900210497, 42284.788576056926, 42398.36402453966, 9254.305927055499, 9327.253819694408, 9373.625054997723, 9401.388909720847, 9444.433333999981, 16435.424760133337, 16472.917635411985, 16479.176041197894, 16500.6699866002, 16704.916475417544, 6373.649449280241, 6473.449756770728, 6501.382368674968, 6559.8032059038305, 6815.965920170367, 3535.7171426285836, 3536.664016918389, 3609.3864043112735, 3652.342578335872, 3736.2714270717493, 2647.377866201441, 2676.2372723773633, 2679.7856171506232, 2680.624712540246, 2681.8870168880085, 454.0895504513429, 471.54260367443527, 474.67247599156474, 492.44845772734766, 508.12602324002654, 540.7863893761942, 549.3325609384617, 561.3039830610014, 574.7270046727771, 589.6845187824579, 7443.404527637885, 7457.0, 7470.738524151738, 7547.622618869034, 7613.390928725688, 322.0340662498416, 426.42858569516994, 440.2010351212539, 479.4222961331587, 509.8317555206773, 3723.3670276053135, 3729.627037296303, 3733.663970242691, 3796.6393192646656, 3837.2901013312626, 49824.0, 53081.2037819433, 54076.729616351666, 54254.45745542494, 54283.0, 38048.61951380506, 38500.8074959629, 44818.77590612026, 45960.770196149475, 46270.0, 37663.0, 38898.22203555913, 39360.606393936265, 39461.80269098636, 39469.605303947166, 7039.472039596948, 7285.162206346126, 7469.626518674153, 7526.09686837583, 7613.428992825449, 30664.926727564427, 31125.688743112278, 31203.37593248168, 31377.90177343782, 31633.0, 37824.81087594544, 38326.0, 40480.797596011835, 40653.39019914763, 40778.0, 9199.494027828267, 9439.52802359893, 9451.385659932082, 9784.657536986171, 9787.314887957775, 10455.163586912877, 10707.946460267649, 10824.0, 10985.945070274598, 11100.833487497532, 10178.18574514052, 10266.640667576898, 10302.742431438974, 10434.113100386248, 10725.517351719036, 10016.298859079794, 10034.548445319831, 10073.546690398804, 10399.27205095636, 10419.114375278186, 9789.412635241624, 10005.599776008878, 10046.146077583488, 10231.28381013322, 10273.178145748472, 7986.201379861967, 8119.5534245617055, 8196.180381961994, 9108.180263776292, 9260.39807412516, 8220.917790822015, 8701.216890479887, 8703.43427677199, 9126.17864392208, 9216.677416290398], "unit": "ms", "name": "yui.html", "value": 5507.223315182014}], "name": "dromaeo_css", "value": 6167.638580110908}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 114.0, 105.0, 112.0, 112.0, 112.0, 102.0, 111.0, 111.0, 105.0], "unit": "ms", "name": "ai-astar", "value": 109.6}, {"lowerIsBetter": true, "replicates": [152.0, 130.0, 155.0, 162.0, 136.0, 154.0, 156.0, 131.0, 171.0, 130.0], "unit": "ms", "name": "audio-beat-detection", "value": 147.7}, {"lowerIsBetter": true, "replicates": [163.0, 162.0, 165.0, 164.0, 163.0, 164.0, 164.0, 165.0, 164.0, 166.0], "unit": "ms", "name": "audio-dft", "value": 164.0}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 85.0, 90.0, 73.0, 72.0, 71.0, 107.0, 89.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 135.0, 95.0, 95.0, 95.0, 140.0, 94.0, 95.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 107.5}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 109.0, 110.0, 109.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 147.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 106.0, 95.0, 95.0, 94.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [70.0, 72.0, 72.0, 71.0, 71.0, 69.0, 72.0, 70.0, 70.0, 70.0], "unit": "ms", "name": "json-parse-financial", "value": 70.7}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 70.0, 68.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.6}, {"lowerIsBetter": true, "replicates": [82.0, 86.0, 88.0, 86.0, 85.0, 86.0, 86.0, 84.0, 84.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.0}, {"lowerIsBetter": true, "replicates": [121.0, 140.0, 136.0, 123.0, 121.0, 120.0, 128.0, 129.0, 122.0, 127.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 126.7}, {"lowerIsBetter": true, "replicates": [137.0, 139.0, 156.0, 144.0, 145.0, 145.0, 157.0, 143.0, 148.0, 143.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.7}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 67.0, 68.0, 69.0, 69.0, 70.0, 67.0, 68.0, 68.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 68.5}], "name": "kraken", "value": 1526.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.0982334489021], "unit": "score", "name": "Richards", "value": 213.824214}, {"lowerIsBetter": false, "replicates": [192.0860545524395], "unit": "score", "name": "DeltaBlue", "value": 344.2103079999998}, {"lowerIsBetter": false, "replicates": [277.39251040221916], "unit": "score", "name": "Encrypt", "value": 959.5825049999997}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [1994.0239043824702], "unit": "score", "name": "RayTrace", "value": 371.1033746253746}, {"lowerIsBetter": false, "replicates": [795.7074721780604], "unit": "score", "name": "Earley", "value": 837.5728811188811}, {"lowerIsBetter": false, "replicates": [12156.626506024097], "unit": "score", "name": "Boyer", "value": 54.82302180376615}, {"lowerIsBetter": false, "replicates": [26921.052631578947], "unit": "score", "name": "RegExp", "value": 33.83913000977518}, {"lowerIsBetter": false, "replicates": [694.4444444444445], "unit": "score", "name": "Splay", "value": 117.34703999999996}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19491.901096833823}]} 05:32:00 INFO - RETURN: dromaeo_css: 6167.64 05:32:00 INFO - RETURN: kraken: 1526.10 05:32:00 INFO - RETURN: v8_7: 19491.90 05:32:00 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,35]]", "result": "19491.90"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,35]]", "result": "1526.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,35]]", "result": "6167.64"}}} 05:32:00 INFO - Return code: 0 05:32:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:32:00 INFO - # TBPL SUCCESS # 05:32:00 INFO - Running post-action listener: _resource_record_post_action 05:32:00 INFO - Running post-run listener: _resource_record_post_run 05:32:01 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 4096; Write bytes: 513671168; Read time: 12; Write time: 439368 05:32:01 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 227545088; Read time: 0; Write time: 262560 05:32:02 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 0; Write bytes: 273268736; Read time: 0; Write time: 174180 05:32:02 INFO - Running post-run listener: _upload_blobber_files 05:32:02 INFO - Blob upload gear active. 05:32:02 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:32:02 INFO - Copying logs to upload dir... 05:32:02 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1259.282470 ========= master_lag: 130.72 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 9 secs) (at 2015-11-25 05:34:11.977749) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 05:34:11.981520) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010030 build_url: 'https://queue.taskcluster.net/v1/task/54RbKcyVSO-IWwsawjokkw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.14 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 05:34:12.135611) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:34:12.135953) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448457061.93862-2106736689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004565 ========= master_lag: 0.17 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 05:34:12.309454) ========= ========= Started reboot skipped (results: 3, elapsed: 4 secs) (at 2015-11-25 05:34:12.309717) ========= ========= Finished reboot skipped (results: 3, elapsed: 4 secs) (at 2015-11-25 05:34:16.765846) ========= ========= Total master_lag: 131.14 =========