builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-015 starttime: 1469117698.92 results: success (0) buildid: 20160721072953 builduid: 18da2a4bcc4045f387c2b02238e07450 revision: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:58.919874) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:58.920587) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:58.920980) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:58.936384) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:58.936786) ========= 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=20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 09:14:59-- 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% 46.5M=0s 2016-07-21 09:14:59 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093639 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.045282) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.045763) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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=20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022043 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.095496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.095862) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf --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=20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 09:14:59,243 truncating revision to first 12 chars 2016-07-21 09:14:59,243 Setting DEBUG logging. 2016-07-21 09:14:59,243 attempt 1/10 2016-07-21 09:14:59,243 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f5154aaeaec4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 09:14:59,545 unpacking tar archive at: fx-team-f5154aaeaec4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.647522 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.758356) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.758715) ========= script_repo_revision: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.759169) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.759465) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 09:14:59.769647) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-21 09:14:59.769985) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:15:00 INFO - MultiFileLogger online at 20160721 09:15:00 in /builds/slave/test 09:15:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 09:15:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:00 INFO - {'append_to_log': False, 09:15:00 INFO - 'base_work_dir': '/builds/slave/test', 09:15:00 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 09:15:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:00 INFO - 'branch': 'Fx-Team-Non-PGO', 09:15:00 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:00 INFO - 'config_files': ('talos/linux_config.py',), 09:15:00 INFO - 'default_actions': ('clobber', 09:15:00 INFO - 'read-buildbot-config', 09:15:00 INFO - 'download-and-extract', 09:15:00 INFO - 'populate-webroot', 09:15:00 INFO - 'create-virtualenv', 09:15:00 INFO - 'install', 09:15:00 INFO - 'run-tests'), 09:15:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:00 INFO - 'download_minidump_stackwalk': True, 09:15:00 INFO - 'download_symbols': 'ondemand', 09:15:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:00 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:00 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:00 INFO - 'installer_path': 'installer.exe', 09:15:00 INFO - 'log_level': 'info', 09:15:00 INFO - 'log_name': 'talos', 09:15:00 INFO - 'log_to_console': True, 09:15:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:15:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:15:00 INFO - 'opt_config_files': (), 09:15:00 INFO - 'pip_index': False, 09:15:00 INFO - 'sps_profile': False, 09:15:00 INFO - 'sps_profile_interval': 0, 09:15:00 INFO - 'suite': 'dromaeojs', 09:15:00 INFO - 'system_bits': '32', 09:15:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:00 INFO - 'title': 'talos-linux64-ix-015', 09:15:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:00 INFO - 'use_talos_json': True, 09:15:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:00 INFO - 'work_dir': 'build'} 09:15:00 INFO - [mozharness: 2016-07-21 16:15:00.013974Z] Running clobber step. 09:15:00 INFO - Running pre-action listener: _resource_record_pre_action 09:15:00 INFO - Running main action method: clobber 09:15:00 INFO - rmtree: /builds/slave/test/build 09:15:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:00 INFO - Running post-action listener: _resource_record_post_action 09:15:00 INFO - [mozharness: 2016-07-21 16:15:00.492951Z] Finished clobber step (success) 09:15:00 INFO - [mozharness: 2016-07-21 16:15:00.493052Z] Running read-buildbot-config step. 09:15:00 INFO - Running pre-action listener: _resource_record_pre_action 09:15:00 INFO - Running main action method: read_buildbot_config 09:15:00 INFO - Using buildbot properties: 09:15:00 INFO - { 09:15:00 INFO - "project": "", 09:15:00 INFO - "product": "firefox", 09:15:00 INFO - "script_repo_revision": "production", 09:15:00 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 09:15:00 INFO - "repository": "", 09:15:00 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 09:15:00 INFO - "buildid": "20160721072953", 09:15:00 INFO - "pgo_build": "False", 09:15:00 INFO - "basedir": "/builds/slave/test", 09:15:00 INFO - "buildnumber": 3190, 09:15:00 INFO - "slavename": "talos-linux64-ix-015", 09:15:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:15:00 INFO - "platform": "ubuntu64_hw", 09:15:00 INFO - "scheduler": "tests-fx-team-linux64-talos", 09:15:00 INFO - "branch": "fx-team", 09:15:00 INFO - "repo_path": "integration/fx-team", 09:15:00 INFO - "revision": "f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf", 09:15:00 INFO - "stage_platform": "linux64", 09:15:00 INFO - "builduid": "18da2a4bcc4045f387c2b02238e07450", 09:15:00 INFO - "slavebuilddir": "test" 09:15:00 INFO - } 09:15:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:15:00 INFO - Running post-action listener: _resource_record_post_action 09:15:00 INFO - [mozharness: 2016-07-21 16:15:00.495511Z] Finished read-buildbot-config step (success) 09:15:00 INFO - [mozharness: 2016-07-21 16:15:00.495601Z] Running download-and-extract step. 09:15:00 INFO - Running pre-action listener: _resource_record_pre_action 09:15:00 INFO - Running main action method: download_and_extract 09:15:00 INFO - mkdir: /builds/slave/test/build/tests 09:15:00 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')]} 09:15:00 INFO - https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:15:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:15:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:15:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:15:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:15:01 INFO - Downloaded 1361 bytes. 09:15:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:15:01 INFO - Using the following test package requirements: 09:15:01 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:01 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:15:01 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:15:01 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'jsshell-linux-x86_64.zip'], 09:15:01 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:15:01 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:15:01 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:15:01 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:15:01 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:15:01 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:15:01 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:15:01 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:15:01 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')]} 09:15:01 INFO - https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:15:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:15:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:15:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:15:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:02 INFO - Downloaded 22735533 bytes. 09:15:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:15:03 INFO - Return code: 0 09:15:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:15: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')]} 09:15:03 INFO - https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:15:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:15:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:15:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:15:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:15:05 INFO - Downloaded 22735533 bytes. 09:15:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:15:06 INFO - Return code: 0 09:15: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')]} 09:15:06 INFO - https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:15:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:15:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:15:08 INFO - Downloaded 14403317 bytes. 09:15:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:15:08 INFO - Return code: 0 09:15: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')]} 09:15:08 INFO - https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:15:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:15:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:15:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:15:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:15:09 INFO - Downloaded 57152095 bytes. 09:15:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:15:09 INFO - mkdir: /builds/slave/test/properties 09:15:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:09 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:09 INFO - Contents: 09:15:09 INFO - build_url:https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:15:10 INFO - Running post-action listener: _resource_record_post_action 09:15:10 INFO - Running post-action listener: set_extra_try_arguments 09:15:10 INFO - [mozharness: 2016-07-21 16:15:10.597035Z] Finished download-and-extract step (success) 09:15:10 INFO - [mozharness: 2016-07-21 16:15:10.597270Z] Running populate-webroot step. 09:15:10 INFO - Running pre-action listener: _resource_record_pre_action 09:15:10 INFO - Running main action method: populate_webroot 09:15:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:10 INFO - '--tppagecycles', 09:15:10 INFO - '7'], 09:15:10 INFO - 'tests': ['tsvgm']}}, 09:15:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['damp', 'tps']}, 09:15:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'tests': ['damp', 'tps']}, 09:15:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['dromaeo_dom']}, 09:15:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['basic_compositor_video']}, 09:15:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:15:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['a11yr', 09:15:10 INFO - 'ts_paint', 09:15:10 INFO - 'tpaint', 09:15:10 INFO - 'sessionrestore', 09:15:10 INFO - 'sessionrestore_no_auto_restore', 09:15:10 INFO - 'tabpaint']}, 09:15:10 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:10 INFO - 'ts_paint', 09:15:10 INFO - 'tpaint', 09:15:10 INFO - 'sessionrestore', 09:15:10 INFO - 'sessionrestore_no_auto_restore', 09:15:10 INFO - 'tabpaint']}, 09:15:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:10 INFO - 'ts_paint', 09:15:10 INFO - 'tpaint', 09:15:10 INFO - 'sessionrestore', 09:15:10 INFO - 'sessionrestore_no_auto_restore', 09:15:10 INFO - 'tabpaint']}, 09:15:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:10 INFO - 'ts_paint', 09:15:10 INFO - 'tpaint', 09:15:10 INFO - 'sessionrestore', 09:15:10 INFO - 'sessionrestore_no_auto_restore', 09:15:10 INFO - 'tabpaint']}, 09:15:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['a11yr', 09:15:10 INFO - 'ts_paint', 09:15:10 INFO - 'tpaint', 09:15:10 INFO - 'sessionrestore', 09:15:10 INFO - 'sessionrestore_no_auto_restore', 09:15:10 INFO - 'tabpaint']}, 09:15:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['a11yr', 09:15:10 INFO - 'ts_paint', 09:15:10 INFO - 'tpaint', 09:15:10 INFO - 'sessionrestore', 09:15:10 INFO - 'sessionrestore_no_auto_restore', 09:15:10 INFO - 'tabpaint']}, 09:15:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['tsvgx', 09:15:10 INFO - 'tsvgr_opacity', 09:15:10 INFO - 'tart', 09:15:10 INFO - 'tscrollx', 09:15:10 INFO - 'cart']}, 09:15:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:10 INFO - 'tsvgr_opacity', 09:15:10 INFO - 'tart', 09:15:10 INFO - 'tscrollx', 09:15:10 INFO - 'cart']}, 09:15:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'talos_options': ['--disable-e10s'], 09:15:10 INFO - 'tests': ['tp5o']}, 09:15:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'tests': ['tp5o']}, 09:15:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'talos_options': ['--disable-e10s', 09:15:10 INFO - '--xperf_path', 09:15:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:10 INFO - 'tests': ['tp5n']}, 09:15:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:10 INFO - 'talos_options': ['--xperf_path', 09:15:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:10 INFO - 'tests': ['tp5n']}}, 09:15:10 INFO - 'talos.zip': {'path': '', 09:15:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:10 INFO - Running post-action listener: _resource_record_post_action 09:15:10 INFO - [mozharness: 2016-07-21 16:15:10.618436Z] Finished populate-webroot step (success) 09:15:10 INFO - [mozharness: 2016-07-21 16:15:10.618526Z] Running create-virtualenv step. 09:15:10 INFO - Running pre-action listener: _resource_record_pre_action 09:15:10 INFO - Running main action method: create_virtualenv 09:15:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15: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 09:15:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:10 INFO - Using real prefix '/usr' 09:15:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:13 INFO - Installing pip.................done. 09:15:13 INFO - Return code: 0 09:15:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:15:13 INFO - Using env: {'DISPLAY': ':0', 09:15:13 INFO - 'HOME': '/home/cltbld', 09:15:13 INFO - 'LANG': 'en_US.UTF-8', 09:15:13 INFO - 'LANGUAGE': 'en_US:en', 09:15:13 INFO - 'LOGNAME': 'cltbld', 09:15:13 INFO - 'MAIL': '/var/mail/cltbld', 09:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:13 INFO - 'MOZ_NO_REMOTE': '1', 09:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:13 INFO - 'NO_EM_RESTART': '1', 09:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:13 INFO - 'PWD': '/builds/slave/test', 09:15:13 INFO - 'SHELL': '/bin/bash', 09:15:13 INFO - 'SHLVL': '1', 09:15:13 INFO - 'TERM': 'linux', 09:15:13 INFO - 'TMOUT': '86400', 09:15:13 INFO - 'USER': 'cltbld', 09:15:13 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:13 INFO - '_': '/tools/buildbot/bin/python'} 09:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15: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 09:15:13 INFO - Cleaning up... 09:15:13 INFO - Return code: 0 09:15:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 09:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 09:15:13 INFO - Using env: {'DISPLAY': ':0', 09:15:13 INFO - 'HOME': '/home/cltbld', 09:15:13 INFO - 'LANG': 'en_US.UTF-8', 09:15:13 INFO - 'LANGUAGE': 'en_US:en', 09:15:13 INFO - 'LOGNAME': 'cltbld', 09:15:13 INFO - 'MAIL': '/var/mail/cltbld', 09:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:13 INFO - 'MOZ_NO_REMOTE': '1', 09:15:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:13 INFO - 'NO_EM_RESTART': '1', 09:15:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:13 INFO - 'PWD': '/builds/slave/test', 09:15:13 INFO - 'SHELL': '/bin/bash', 09:15:13 INFO - 'SHLVL': '1', 09:15:13 INFO - 'TERM': 'linux', 09:15:13 INFO - 'TMOUT': '86400', 09:15:13 INFO - 'USER': 'cltbld', 09:15:13 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:13 INFO - '_': '/tools/buildbot/bin/python'} 09:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:13 INFO - Downloading/unpacking psutil>=3.1.1 09:15:13 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 09:15:13 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 09:15:13 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 09:15:13 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 09:15:13 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 09:15:13 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 09:15:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:15 INFO - Installing collected packages: psutil 09:15:15 INFO - Running setup.py install for psutil 09:15:15 INFO - building 'psutil._psutil_linux' extension 09:15: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 09:15:15 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 09:15:15 INFO - building 'psutil._psutil_posix' extension 09:15:15 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 09:15: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 09:15:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:16 INFO - Successfully installed psutil 09:15:16 INFO - Cleaning up... 09:15:16 INFO - Return code: 0 09:15:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 09:15:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 09:15:16 INFO - Using env: {'DISPLAY': ':0', 09:15:16 INFO - 'HOME': '/home/cltbld', 09:15:16 INFO - 'LANG': 'en_US.UTF-8', 09:15:16 INFO - 'LANGUAGE': 'en_US:en', 09:15:16 INFO - 'LOGNAME': 'cltbld', 09:15:16 INFO - 'MAIL': '/var/mail/cltbld', 09:15:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:16 INFO - 'MOZ_NO_REMOTE': '1', 09:15:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:16 INFO - 'NO_EM_RESTART': '1', 09:15:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:16 INFO - 'PWD': '/builds/slave/test', 09:15:16 INFO - 'SHELL': '/bin/bash', 09:15:16 INFO - 'SHLVL': '1', 09:15:16 INFO - 'TERM': 'linux', 09:15:16 INFO - 'TMOUT': '86400', 09:15:16 INFO - 'USER': 'cltbld', 09:15:16 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:16 INFO - '_': '/tools/buildbot/bin/python'} 09:15:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:15:18 INFO - Installing collected packages: mozsystemmonitor 09:15:18 INFO - Running setup.py install for mozsystemmonitor 09:15:18 INFO - Successfully installed mozsystemmonitor 09:15:18 INFO - Cleaning up... 09:15:18 INFO - Return code: 0 09:15:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1'] in /builds/slave/test/build 09:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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==2.5.1 09:15:18 INFO - Using env: {'DISPLAY': ':0', 09:15:18 INFO - 'HOME': '/home/cltbld', 09:15:18 INFO - 'LANG': 'en_US.UTF-8', 09:15:18 INFO - 'LANGUAGE': 'en_US:en', 09:15:18 INFO - 'LOGNAME': 'cltbld', 09:15:18 INFO - 'MAIL': '/var/mail/cltbld', 09:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:18 INFO - 'MOZ_NO_REMOTE': '1', 09:15:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:18 INFO - 'NO_EM_RESTART': '1', 09:15:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:18 INFO - 'PWD': '/builds/slave/test', 09:15:18 INFO - 'SHELL': '/bin/bash', 09:15:18 INFO - 'SHLVL': '1', 09:15:18 INFO - 'TERM': 'linux', 09:15:18 INFO - 'TMOUT': '86400', 09:15:18 INFO - 'USER': 'cltbld', 09:15:18 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:18 INFO - '_': '/tools/buildbot/bin/python'} 09:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:15:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:15:20 INFO - warning: no files found matching '*.txt' 09:15:20 INFO - no previously-included directories found matching 'build' 09:15:20 INFO - no previously-included directories found matching 'dist' 09:15:20 INFO - no previously-included directories found matching '.git*' 09:15:20 INFO - Installing collected packages: jsonschema, functools32 09:15:20 INFO - Running setup.py install for functools32 09:15:21 INFO - warning: no files found matching '*.txt' 09:15:21 INFO - no previously-included directories found matching 'build' 09:15:21 INFO - no previously-included directories found matching 'dist' 09:15:21 INFO - no previously-included directories found matching '.git*' 09:15:21 INFO - Successfully installed jsonschema functools32 09:15:21 INFO - Cleaning up... 09:15:21 INFO - Return code: 0 09:15:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:15:21 INFO - Using env: {'DISPLAY': ':0', 09:15:21 INFO - 'HOME': '/home/cltbld', 09:15:21 INFO - 'LANG': 'en_US.UTF-8', 09:15:21 INFO - 'LANGUAGE': 'en_US:en', 09:15:21 INFO - 'LOGNAME': 'cltbld', 09:15:21 INFO - 'MAIL': '/var/mail/cltbld', 09:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:21 INFO - 'MOZ_NO_REMOTE': '1', 09:15:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:21 INFO - 'NO_EM_RESTART': '1', 09:15:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:21 INFO - 'PWD': '/builds/slave/test', 09:15:21 INFO - 'SHELL': '/bin/bash', 09:15:21 INFO - 'SHLVL': '1', 09:15:21 INFO - 'TERM': 'linux', 09:15:21 INFO - 'TMOUT': '86400', 09:15:21 INFO - 'USER': 'cltbld', 09:15:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:21 INFO - '_': '/tools/buildbot/bin/python'} 09:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:15:21 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 09:15:21 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 09:15:21 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 09:15:21 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 09:15:21 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 09:15:21 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 09:15:23 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 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 09:15:23 INFO - Downloading docopt-0.6.1.tar.gz 09:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:24 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:24 INFO - Running setup.py install for blobuploader 09:15:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:24 INFO - Running setup.py install for requests 09:15:24 INFO - Running setup.py install for docopt 09:15:24 INFO - Successfully installed blobuploader requests docopt 09:15:24 INFO - Cleaning up... 09:15:24 INFO - Return code: 0 09:15:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 09:15:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --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 09:15:24 INFO - Using env: {'DISPLAY': ':0', 09:15:24 INFO - 'HOME': '/home/cltbld', 09:15:24 INFO - 'LANG': 'en_US.UTF-8', 09:15:24 INFO - 'LANGUAGE': 'en_US:en', 09:15:24 INFO - 'LOGNAME': 'cltbld', 09:15:24 INFO - 'MAIL': '/var/mail/cltbld', 09:15:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:24 INFO - 'MOZ_NO_REMOTE': '1', 09:15:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:24 INFO - 'NO_EM_RESTART': '1', 09:15:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:24 INFO - 'PWD': '/builds/slave/test', 09:15:24 INFO - 'SHELL': '/bin/bash', 09:15:24 INFO - 'SHLVL': '1', 09:15:24 INFO - 'TERM': 'linux', 09:15:24 INFO - 'TMOUT': '86400', 09:15:24 INFO - 'USER': 'cltbld', 09:15:24 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:24 INFO - '_': '/tools/buildbot/bin/python'} 09:15:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:24 INFO - Running setup.py (path:/tmp/pip-C4WF9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:24 INFO - Running setup.py (path:/tmp/pip-FFA0xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:24 INFO - Running setup.py (path:/tmp/pip-PEJ_8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:25 INFO - Running setup.py (path:/tmp/pip-aMpepp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:25 INFO - Running setup.py (path:/tmp/pip-Hgh8cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:25 INFO - Running setup.py (path:/tmp/pip-wOVEP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:25 INFO - Running setup.py (path:/tmp/pip-g2tZBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:25 INFO - Running setup.py (path:/tmp/pip-MfePHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:25 INFO - Running setup.py (path:/tmp/pip-3qcDpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:25 INFO - Running setup.py (path:/tmp/pip-eQYZi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:25 INFO - Running setup.py (path:/tmp/pip-G47EJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:25 INFO - Running setup.py (path:/tmp/pip-70hiDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:25 INFO - Running setup.py (path:/tmp/pip-WK_Gmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:25 INFO - Running setup.py (path:/tmp/pip-ysvFKW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:25 INFO - Running setup.py (path:/tmp/pip-QPgbow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:25 INFO - Running setup.py (path:/tmp/pip-cu91nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:26 INFO - Running setup.py (path:/tmp/pip-PNdEnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:15:26 INFO - Running setup.py install for manifestparser 09:15:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Running setup.py install for mozcrash 09:15:26 INFO - Running setup.py install for mozdebug 09:15:26 INFO - Running setup.py install for mozdevice 09:15:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Running setup.py install for mozfile 09:15:26 INFO - Running setup.py install for mozhttpd 09:15:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Running setup.py install for mozinfo 09:15:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Running setup.py install for mozInstall 09:15:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:15:26 INFO - Running setup.py install for mozleak 09:15:26 INFO - Running setup.py install for mozlog 09:15:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Running setup.py install for moznetwork 09:15:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Running setup.py install for mozprocess 09:15:27 INFO - Running setup.py install for mozprofile 09:15:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Running setup.py install for mozrunner 09:15:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Running setup.py install for mozscreenshot 09:15:27 INFO - Running setup.py install for moztest 09:15:27 INFO - Running setup.py install for mozversion 09:15:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:15:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:15:27 INFO - Cleaning up... 09:15:27 INFO - Return code: 0 09:15:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:27 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')]} 09:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:27 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')]} 09:15:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:15:27 INFO - Using env: {'DISPLAY': ':0', 09:15:27 INFO - 'HOME': '/home/cltbld', 09:15:27 INFO - 'LANG': 'en_US.UTF-8', 09:15:27 INFO - 'LANGUAGE': 'en_US:en', 09:15:27 INFO - 'LOGNAME': 'cltbld', 09:15:27 INFO - 'MAIL': '/var/mail/cltbld', 09:15:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:27 INFO - 'MOZ_NO_REMOTE': '1', 09:15:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:27 INFO - 'NO_EM_RESTART': '1', 09:15:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:27 INFO - 'PWD': '/builds/slave/test', 09:15:27 INFO - 'SHELL': '/bin/bash', 09:15:27 INFO - 'SHLVL': '1', 09:15:27 INFO - 'TERM': 'linux', 09:15:27 INFO - 'TMOUT': '86400', 09:15:27 INFO - 'USER': 'cltbld', 09:15:27 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:27 INFO - '_': '/tools/buildbot/bin/python'} 09:15:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:28 INFO - Running setup.py (path:/tmp/pip-k8SLIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:28 INFO - Running setup.py (path:/tmp/pip-9aMNGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:28 INFO - Running setup.py (path:/tmp/pip-dQGgKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:28 INFO - Running setup.py (path:/tmp/pip-Hswl_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:28 INFO - Running setup.py (path:/tmp/pip-iv82Cj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:28 INFO - Running setup.py (path:/tmp/pip-0z7joj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:28 INFO - Running setup.py (path:/tmp/pip-VKfto3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:28 INFO - Running setup.py (path:/tmp/pip-CQjD7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:28 INFO - Running setup.py (path:/tmp/pip-9ipBaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:28 INFO - Running setup.py (path:/tmp/pip-mYKLs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:28 INFO - Running setup.py (path:/tmp/pip-h5XouI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:28 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:28 INFO - Running setup.py (path:/tmp/pip-ja7tq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 09:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:28 INFO - Running setup.py (path:/tmp/pip-1CtSfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 09:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:29 INFO - Running setup.py (path:/tmp/pip-r978zO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 09:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:29 INFO - Running setup.py (path:/tmp/pip-LZGnj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:29 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)) 09:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:29 INFO - Running setup.py (path:/tmp/pip-RXYi9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:29 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)) 09:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:29 INFO - Running setup.py (path:/tmp/pip-Z5glh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:29 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)) 09:15:29 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:29 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:15:29 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)) 09:15:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:29 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:15:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:29 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 09:15:29 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 09:15:29 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 09:15:29 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 09:15:29 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 09:15:29 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 09:15:31 INFO - Downloading blessings-1.6.tar.gz 09:15:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:15:31 INFO - Installing collected packages: blessings 09:15:31 INFO - Running setup.py install for blessings 09:15:31 INFO - Successfully installed blessings 09:15:31 INFO - Cleaning up... 09:15:31 INFO - Return code: 0 09:15:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:31 INFO - Reading from file tmpfile_stdout 09:15:31 INFO - Current package versions: 09:15:31 INFO - argparse == 1.2.1 09:15:31 INFO - blessings == 1.6 09:15:31 INFO - blobuploader == 1.2.4 09:15:31 INFO - docopt == 0.6.1 09:15:31 INFO - functools32 == 3.2.3-2 09:15:31 INFO - jsonschema == 2.5.1 09:15:31 INFO - manifestparser == 1.1 09:15:31 INFO - mozInstall == 1.12 09:15:31 INFO - mozcrash == 0.17 09:15:31 INFO - mozdebug == 0.1 09:15:31 INFO - mozdevice == 0.48 09:15:31 INFO - mozfile == 1.2 09:15:31 INFO - mozhttpd == 0.7 09:15:31 INFO - mozinfo == 0.9 09:15:31 INFO - mozleak == 0.1 09:15:31 INFO - mozlog == 3.3 09:15:31 INFO - moznetwork == 0.27 09:15:31 INFO - mozprocess == 0.23 09:15:31 INFO - mozprofile == 0.28 09:15:31 INFO - mozrunner == 6.12 09:15:31 INFO - mozscreenshot == 0.1 09:15:31 INFO - mozsystemmonitor == 0.3 09:15:31 INFO - moztest == 0.7 09:15:31 INFO - mozversion == 1.4 09:15:31 INFO - psutil == 3.1.1 09:15:31 INFO - requests == 1.2.3 09:15:31 INFO - wsgiref == 0.1.2 09:15:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:31 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')]} 09:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:31 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')]} 09:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:15:31 INFO - Using env: {'DISPLAY': ':0', 09:15:31 INFO - 'HOME': '/home/cltbld', 09:15:31 INFO - 'LANG': 'en_US.UTF-8', 09:15:31 INFO - 'LANGUAGE': 'en_US:en', 09:15:31 INFO - 'LOGNAME': 'cltbld', 09:15:31 INFO - 'MAIL': '/var/mail/cltbld', 09:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:31 INFO - 'MOZ_NO_REMOTE': '1', 09:15:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:31 INFO - 'NO_EM_RESTART': '1', 09:15:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:31 INFO - 'PWD': '/builds/slave/test', 09:15:31 INFO - 'SHELL': '/bin/bash', 09:15:31 INFO - 'SHLVL': '1', 09:15:31 INFO - 'TERM': 'linux', 09:15:31 INFO - 'TMOUT': '86400', 09:15:31 INFO - 'USER': 'cltbld', 09:15:31 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:31 INFO - '_': '/tools/buildbot/bin/python'} 09:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:15:31 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 2)) 09:15:31 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 3)) 09:15:31 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 4)) 09:15:31 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 5)) 09:15:31 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 6)) 09:15:31 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 7)) 09:15:31 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 8)) 09:15:31 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 9)) 09:15:31 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:15:31 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 4)) 09:15:31 INFO - Cleaning up... 09:15:31 INFO - Return code: 0 09:15:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:15:31 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')]} 09:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:31 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')]} 09:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10fe030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bf180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, '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': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', '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 09:15:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 09:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 09:15:31 INFO - Using env: {'DISPLAY': ':0', 09:15:31 INFO - 'HOME': '/home/cltbld', 09:15:31 INFO - 'LANG': 'en_US.UTF-8', 09:15:31 INFO - 'LANGUAGE': 'en_US:en', 09:15:31 INFO - 'LOGNAME': 'cltbld', 09:15:31 INFO - 'MAIL': '/var/mail/cltbld', 09:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:31 INFO - 'MOZ_NO_REMOTE': '1', 09:15:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:31 INFO - 'NO_EM_RESTART': '1', 09:15:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:31 INFO - 'PWD': '/builds/slave/test', 09:15:31 INFO - 'SHELL': '/bin/bash', 09:15:31 INFO - 'SHLVL': '1', 09:15:31 INFO - 'TERM': 'linux', 09:15:31 INFO - 'TMOUT': '86400', 09:15:31 INFO - 'USER': 'cltbld', 09:15:31 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:31 INFO - '_': '/tools/buildbot/bin/python'} 09:15:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:15:32 INFO - Cleaning up... 09:15:32 INFO - Return code: 0 09:15:32 INFO - Running post-action listener: _resource_record_post_action 09:15:32 INFO - Running post-action listener: _start_resource_monitoring 09:15:32 INFO - Starting resource monitoring. 09:15:32 INFO - [mozharness: 2016-07-21 16:15:32.140116Z] Finished create-virtualenv step (success) 09:15:32 INFO - [mozharness: 2016-07-21 16:15:32.140556Z] Running install step. 09:15:32 INFO - Running pre-action listener: _resource_record_pre_action 09:15:32 INFO - Running main action method: install 09:15:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:32 INFO - Reading from file tmpfile_stdout 09:15:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:15:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:15:32 INFO - Reading from file tmpfile_stdout 09:15:32 INFO - Output received: 09:15:32 INFO - Usage: mozinstall [options] installer 09:15:32 INFO - Options: 09:15:32 INFO - -h, --help show this help message and exit 09:15:32 INFO - -d DEST, --destination=DEST 09:15:32 INFO - Directory to install application into. [default: 09:15:32 INFO - "/builds/slave/test"] 09:15:32 INFO - --app=APP Application being installed. [default: firefox] 09:15:32 INFO - mkdir: /builds/slave/test/build/application 09:15:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:15:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:15:49 INFO - Reading from file tmpfile_stdout 09:15:49 INFO - Output received: 09:15:49 INFO - /builds/slave/test/build/application/firefox/firefox 09:15:49 INFO - Running post-action listener: _resource_record_post_action 09:15:49 INFO - [mozharness: 2016-07-21 16:15:49.489586Z] Finished install step (success) 09:15:49 INFO - [mozharness: 2016-07-21 16:15:49.489875Z] Running run-tests step. 09:15:49 INFO - Running pre-action listener: _resource_record_pre_action 09:15:49 INFO - Running main action method: run_tests 09:15:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:15:49 INFO - Python 2.7.3 09:15:49 INFO - Return code: 0 09:15:49 INFO - grabbing minidump binary from tooltool 09:15:49 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')]} 09:15:49 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13bd3b0>, '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 0x13be2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13be740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:15:49 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 09:15:49 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 09:15:49 INFO - Calling ['/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'] with output_timeout 600 09:15:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:15:49 INFO - Return code: 0 09:15:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:15:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:15:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:15:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:15:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:15:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:15:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:15:49 INFO - Using env: {'DISPLAY': ':0', 09:15:49 INFO - 'HOME': '/home/cltbld', 09:15:49 INFO - 'LANG': 'en_US.UTF-8', 09:15:49 INFO - 'LANGUAGE': 'en_US:en', 09:15:49 INFO - 'LOGNAME': 'cltbld', 09:15:49 INFO - 'MAIL': '/var/mail/cltbld', 09:15:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:15:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:15:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:49 INFO - 'MOZ_NO_REMOTE': '1', 09:15:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:15:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:15:49 INFO - 'NO_EM_RESTART': '1', 09:15:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:15:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:49 INFO - 'PWD': '/builds/slave/test', 09:15:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:15:49 INFO - 'SHELL': '/bin/bash', 09:15:49 INFO - 'SHLVL': '1', 09:15:49 INFO - 'TERM': 'linux', 09:15:49 INFO - 'TMOUT': '86400', 09:15:49 INFO - 'USER': 'cltbld', 09:15:49 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259', 09:15:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:49 INFO - '_': '/tools/buildbot/bin/python'} 09:15:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:15:49 INFO - mozversion application_buildid: 20160721072953 09:15:49 INFO - mozversion application_changeset: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf 09:15:49 INFO - mozversion application_display_name: Nightly 09:15:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:15:49 INFO - mozversion application_name: Firefox 09:15:49 INFO - mozversion application_remotingname: firefox 09:15:49 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:15:49 INFO - mozversion application_vendor: Mozilla 09:15:49 INFO - mozversion application_version: 50.0a1 09:15:49 INFO - mozversion platform_buildid: 20160721072953 09:15:49 INFO - mozversion platform_changeset: f5154aaeaec4546dd04c66ac61a0d0ee69f4dfdf 09:15:49 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:15:49 INFO - mozversion platform_version: 50.0a1 09:15:49 INFO - using testdate: 1469117749 09:15:49 INFO - actual date: 1469117749 09:15:49 INFO - starting webserver on 'localhost:40532' 09:15:49 INFO - SUITE-START | Running 2 tests 09:15:49 INFO - TEST-START | dromaeo_css 09:15:49 INFO - operating with platform_type : linux_ 09:15:49 INFO - Initialising browser for dromaeo_css test... 09:15:49 INFO - TEST-INFO | started process 8946 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcKSzgx/profile http://localhost:40532/getInfo.html) 09:15:53 INFO - PROCESS | 8946 | JavaScript error: , line 0: uncaught exception: undefined 09:15:53 INFO - PROCESS | 8946 | __metrics Screen width/height:1600/1200 09:15:53 INFO - PROCESS | 8946 | colorDepth:24 09:15:53 INFO - PROCESS | 8946 | Browser inner width/height: 1024/697 09:15:53 INFO - PROCESS | 8946 | __metrics 09:15:54 INFO - PROCESS | 8946 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:15:54 INFO - PROCESS | 8946 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:15:55 INFO - TEST-INFO | 8946: exit 0 09:15:55 INFO - Browser initialized. 09:15:55 INFO - Running cycle 1/1 for dromaeo_css test... 09:15:55 INFO - TEST-INFO | started process 9027 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcKSzgx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:07 INFO - PROCESS | 9027 | RSS: Main: 159264768 09:16:07 INFO - PROCESS | 9027 | 09:16:59 INFO - PROCESS | 9027 | 1469117819549 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] 09:19:11 INFO - PROCESS | 9027 | Cycle 1(1): loaded http://localhost:40532/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40532/tests/dromaeo/cssquery-ext.html) 09:19:12 INFO - PROCESS | 9027 | RSS: Main: 247017472 09:19:12 INFO - PROCESS | 9027 | 09:22:14 INFO - PROCESS | 9027 | Cycle 1(1): loaded http://localhost:40532/tests/dromaeo/cssquery-ext.html (next: http://localhost:40532/tests/dromaeo/cssquery-jquery.html) 09:22:15 INFO - PROCESS | 9027 | RSS: Main: 244142080 09:22:15 INFO - PROCESS | 9027 | 09:25:17 INFO - PROCESS | 9027 | Cycle 1(1): loaded http://localhost:40532/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40532/tests/dromaeo/cssquery-mootools.html) 09:25:18 INFO - PROCESS | 9027 | RSS: Main: 192630784 09:25:18 INFO - PROCESS | 9027 | 09:28:20 INFO - PROCESS | 9027 | Cycle 1(1): loaded http://localhost:40532/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40532/tests/dromaeo/cssquery-prototype.html) 09:28:21 INFO - PROCESS | 9027 | RSS: Main: 189763584 09:28:21 INFO - PROCESS | 9027 | 09:31:24 INFO - PROCESS | 9027 | Cycle 1(1): loaded http://localhost:40532/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40532/tests/dromaeo/cssquery-yui.html) 09:31:24 INFO - PROCESS | 9027 | RSS: Main: 190312448 09:31:24 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | Cycle 1(1): loaded http://localhost:40532/tests/dromaeo/cssquery-yui.html (next: http://localhost:40532/tests/dromaeo/cssquery-dojo.html) 09:34:27 INFO - PROCESS | 9027 | RSS: Main: 282984448 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | __start_tp_report 09:34:27 INFO - PROCESS | 9027 | _x_x_mozilla_page_load 09:34:27 INFO - PROCESS | 9027 | _x_x_mozilla_page_load_details 09:34:27 INFO - PROCESS | 9027 | |i|pagename|runs| 09:34:27 INFO - PROCESS | 9027 | |0;dojo.html;2561.628563858241;2569.3833479964815;2606.7393260673934;2618.6988496322915;2621.1219241554068;4543.568361005708;4611.19304121779;4675.742834144121;4680.5787479126875;4698.694584851983;4575.725456472605;4602.9769851150695;4614.4462664480225;4628.120657075154;4670.229412147;4189.811458484375;4238.533761286256;4244.554321796213;4305.892352691192;4452;4375.124975004996;4403.779811009453;4435;4447.4218351614245;4454.621357184627;4206.242876282268;4254.7872606369865;4259.467566554181;4273.145370925828;4368.104538569585;4235.4917409911;4245.766482843443;4254.31930891056;4308.246056940023;4318.848840290575;4214.662826973835;4263.445752052245;4280.5291417944;4299.247631664446;4332.371806088131;4553.043860789238;4663.370444989917;4690.132325519789;4719.4100737407825;4741.786619602091;4942;4953.405591329064;5012.022655582163;5081.79672813087;5083.3391659084455;5056.82301119459;5057.443681195065;5068.543831055223;5090.032893750175;5104.51507106828;4566.06395688883;4624.075184962953;4760.809567617326;4765.833195838199;4772.045590881838;5095.821646242363;5157.149070403417;5165.638405311667;5169.379674439091;5178.663386879841;4893.437254715682;4924.75376231187;4924.8768780780765;4970.080535101018;4980.203167493184;4843.346148270009;4914.140025495524;4961.280614310905;4992.700437973734;5006.949930500721;4838.685485443505;4933.309336692866;4933.506649335038;4963.57809586182;4964.677295975822;4911.901761964812;4918.336024636628;4921.064997650435;4923.87690307738;4968.130577148984;4142.337226043818;4177.081042170778;4184.66522678185;4190.224808410454;4241.1093670328955;3678.0988657779017;3695.981520092382;3722.181120153562;3761.1161377076364;3816.160444702161;4344.391785150082;4346;4346.695731298842;4377.737335759864;4387.859156619238;4857.489963553847;5042.142835717937;5042.596592272609;5047.369078865142;5082.491750824888;4238.4490016298305;4280.272353699878;4326.6754993375625;4335.089631177425;4373.431453910971;4492.752898590609;4520.864374068783;4522.66080043998;4525.094981003813;4568.063546972929;4632.606228470618;4635.536446355405;4641.86074417768;4644.674872758875;4685.898813778759;4874.171390863633;4952.5542701157065;4990.101781679333;4990.301357809837;5086.771095300646;4636.397268355092;4641.721496710208;4666.463356714022;4668.486466488615;4719.2921061839625;4942.901141977212;4949.554540091409;4959.851204463872;4962.578180854668;5003.774075351416;4859.829905953275;4875.07373599015;4889.755512224446;4923.876903077452;4986.6010719143105;4785.880352991203;4807.519248075165;4815.686980346266;4819.132556139928;4859.441164266154;4789.185838407409;4805.8558243252755;4806.255030470283;4807.6153907688;4854.8786280341865;4633.976830115827;4739.620830333496;4756.904861902812;4770.761461926959;4830.033993201304;4851.32081508582;4871.853844384674;4884.804607815648;4886.780094895809;4931.802727890844;4764.547368000034;4773.785179666854;4775.498572649822;4790.8323208687525;4829.710217386968 09:34:27 INFO - PROCESS | 9027 | |1;ext.html;549.9697516636585;574.7126436781609;577.0651544497913;577.904645733454;580.3210244014502;27314;39377.60622393775;39768.40347394792;40081.39877901834;44052;41026.17947641053;41143.58856411435;41426;41462.58537414625;41464.79267603658;7395;7429.517081389719;7564.394848412141;7564.546127232356;7570;7550.962245188766;7637.236276372374;7639.809004774897;9679.128878400943;9919.652812151577;10093.394396336244;10110.797784044315;10152.949235253813;10158.593656253777;10159.441230732307;10061.144802691744;10104.242181836391;10121.03850134236;10123.139533139693;10127.696169114939;10020.89979100207;10113.140383067448;10113.848292275621;10115.696529104138;10116.393016419039;38627.80686096579;38713.61286387157;38767.418488722695;38838.22323553541;38847.028824279616;2856.9142925712054;2911.213972227487;2923.3422479942055;2929;2935.148806846012;28031.29921751952;28802.450595243983;28837.711622883922;28875.27811804701;28992.710072899423;3173.254285242966;3231.6104075247636;3286.5398844161837;3356.63077061523;3357.2949680567112;263320;263892.680536598;264586;264711;265964;53098.469015310126;53495.465045349825;53786;53847.19229211565;53848.19227711587;34899.82550087284;35483;35523.82238088793;57073.71463142658;57166.142507862416;14307.92846035763;14327.283635818167;14403.135811851322;14424.06243594163;14429.639259018399;10893.564257429773;10983.505742241618;10993.505292261867;10995.615153469591;10997.890021099847;16292.592685182964;17760.91119544394;18059.729104063448;18150.818491814913;18647.720284195748;6552.3775241352;7016.894746578805;7055.611941343275;7084.610346430893;7211.855762884713;795.3478147918767;876.8421684096924;911.3392790227165;944.2776276148752;969.4377261188494;27792.861035694692;28021;28022.85988570044;28354.574681379796;28600.427991440054;951.438651195784;961.4615815143402;974.4238545310129;974.5565767575736;982.2976571751232;14451.638709032359;14710;14927.40290388394;15053.698926021638;15291.694166116615;381033;385504;385548;387358;388950;49904.251436228486;50475.74762126166;50495.74752126289;50619.493805062215;50686.493135068915;1082.4912291223113;1161.2742036227357;1168.7779321928977;1194.4624918786408;1197.5628895453272;27418.5887211688;28683.13950581486;29013.41973160567;29488.41023179567;29612.259693507836;46761.76619116883;47908;47948;52865.47134528605;52876.735616321675;350994;353801;354962;355062;356038;24817;25760.742392575834;25782;26750.331241719112;26931.596026059233;41351.37972930348;41816.790916045225;41837.79081104575;42379.7881010593;42828.35757463578;35920.28159436744;36005.819970899975;36006;36384;36946.44580331351;36531.452028219064;36561.63438365582;36803;37154.25691486101;37764;35117.82441087778;35570.822145889106;35822.8208858954;35897.64102358943;36623.63376366202;30734;32672.836635816668;32953;33004.83497582593;33064.50403243905;24692.259232223063;24944.750552494243;25033.74966250314;25230.74769252284;25310.746892531577 09:34:27 INFO - PROCESS | 9027 | |2;jquery.html;333.1687439837606;360.38914040701025;362.3495824994136;364.3932851801749;384.0494775430809;12456.564020259293;16059.19704014799;16455.588610284747;16649.583760405996;17797.73303400447;16471.258793354296;16619.584510387245;16743.497695069167;17114.914425427854;17212.31150753968;4800.759962001904;5015.799368025275;5029.698218106907;5287.86780330491;5338.119210330291;3432.1076520104702;3533.805640689761;5406.594285485782;5417.322834645669;5438.456154384569;3528.894133176009;3654.506641603376;3660.103274697703;4720.409948756405;5874.76500939964;5862.853428664275;5915.408459154093;5925.940740592603;5926.940730592682;5928.140419639129;5118.539331460149;5242;5321.068812957717;5333.519983201491;5337.919931201035;12427.565035223814;12559.560415385418;14754.70490590193;20412;20794.5841083179;1695.1100672147222;1823.525883270346;1859.6745569525415;1925;1929.556202073531;8847.07105753893;8903.705831942152;9136.406133601295;9292.53537323131;9428.434293942386;1627.6581917797278;1717.3474079849702;1728.08411541883;1773.2375078716148;1813.8730288879783;62711;65833.67083164601;69785.65107174483;71497.64251178764;72607.63696181485;9684.370515916444;9712;9806.53603842516;9818.165455936325;10678.839817402744;13623.318834058258;13638;13649.249291288876;13656.522021729192;13679.452821887213;6685.164354455693;6715.597064176165;6798.456123510107;6895.275996020446;7105.111861017373;6482.222133343935;6524.184476940382;6742.49431292655;6803.693833777492;6839.076724642209;4180.581941805856;4197.244495990751;4242.915141697211;4250.893727656833;4347.152305300436;1741.2116679132387;1767.3814165042133;1775.4052392448416;1810.710286354177;1826.3972888946616;784.9803754906173;822.4695071678734;860.0582362313257;874.0560194989396;929.1683942871227;16086;16756.664866702842;17043.65912681764;17468.563285917953;17555.561110972074;819.4100247821559;822.43663090783;830.7009476588423;845.2815107158866;880.9691660791841;9131.315151363675;9385.765355866022;9583.377080489747;9587.760305992406;9657.565409556446;372031;375353;383863;385358;386598;77714;78102.6094869522;78967.21032789713;79140;80270;876.8903887014123;879.6525372477963;897.82043591282;899.2895612466078;916.5554705967601;14336.222457595164;14853.702925941205;14952.925235373754;14953.865733375616;14980.625484362978;33944.830275848464;34993.475097873044;35284.64715352814;35511;36859.815700922394;49166.508334916194;49682.75158624184;50088.24867627062;50810.745946270035;51436;7157.355837974607;7241.493095483265;7245.5652660840515;7250.3112204340505;7544.132424771222;8843.380963332762;10424.791504170025;10444.425556594157;10675.199360047873;10880.564777408816;8734.126587341216;8884.111588841064;8977.775555611162;9003.324750643596;9048.457092574467;9168.129027742354;9225.907740922505;9358;9381.859272110787;9522.14300712939;7771.9896413465885;8929.196372326522;8930.732078037669;9105.043970383014;9605.759856003655;7138.393236574741;7200.1719802223415;7264.164621068645;7299.343059124704;7562;7428.739994100397;8883.555822208735;9048.909510904807;9127.08729127082;9247.260219182584 09:34:27 INFO - PROCESS | 9027 | |3;mootools.html;1274.4010315151875;1341.8456877459091;1424.5014245014245;1454.483658301303;1684.0400971446281;6772.932270677292;7749;8006;8009.439339246248;8260.50436973782;8026.959865200681;8101.02787665481;8110.270075693186;8113.797155071112;8124.634391452384;2150.236665983573;2167.4906396996694;2171.826253899684;2171.934841954736;2178.2920550821045;2087.5929193807215;2123.002188971181;2169.9240526581575;2176.706144670473;2181.923632672857;2177.0203408466173;2200.526886719355;2214.446388402899;2218.5562887422498;2220.8334374921947;2120.544083022158;2120.8621439606377;2127.95744085119;2128.755193152784;2132.3283165802723;2083.8228750556223;2114.164904862586;2121.1833444123968;2127.149140343875;2127.936161915145;7939.523628582304;8015.118336982927;8067.95966020172;8112.351011919032;8121.878171827428;4537.251353526664;4735.857924262277;4737.170995075882;4743.691660042121;4749.762511874393;2787.6236708044357;2916.387558612673;2956.9408611827644;2959.1566403575093;2970.0347387099278;828.1511450762956;839.9790005249856;840.9621567029499;1615.4184493582418;1648.785657864482;10613.893861061444;11113;11182.16133789969;11213.15901307397;11221.04621107207;4131.338985762323;4242.8727138185905;4331.068820203672;4424.3142312941545;4430.13612345596;9422.858657120149;9425.905740942502;9428.481433521221;9429.104235097655;9447.385919915183;4752.097101550694;4757.35775670287;4760.285957106462;4762.809487620457;4762.857114286577;3760.2291530236234;4185.24665560196;4344.543822898613;4356.607905288539;4364.061726728769;3645.7265705072227;3673.173535954389;3818.8281527331337;3823.0824602095295;3843.615638436134;1166.3177041430688;1201.7296108375544;1206.8250103734908;1213.096243298748;1215.8723334049973;746.8095635612884;774.9612519374009;787.8576064706199;787.8739401695816;788.7949133225403;4951.925721114186;4991.153273288906;4999.900001999939;5032.547070763649;5260.237265596467;390.0852500885419;395.89939966892086;408.93457046872356;410.03436906086336;411.4527678188003;3272.869085236564;3294.802311861248;3373.5323541561725;3404.3701915145284;3542.875999340011;9649.710508684751;9984.750381240381;10063.496825158712;10099.495025248709;10104.292699510963;9391.577379017825;10011.549480273405;10022.599096036078;10031.197504199647;10050.798984020279;7133.144022717307;7308.415326773846;7312.561246325237;7313.158986716596;7315.780526584211;5058;5119.104156772475;5152.227165924991;5170.302009228706;5373.865653358697;8857;8863.290936724918;8871.068537803696;8877.023527412108;8971.416857904216;8830.249428798625;8989.685361012333;9171.862422063808;9183.862242066241;9436.905630943602;5733.770649173986;6780.423663988419;6974.476914231352;7121.394681452135;7135.429165666838;7565.583892885724;7693.730719424793;7756.8448631028195;7757.224277572198;7766.728164514215;6648.966755166193;6764.864702706017;6811.080504131879;6893.448524118159;6974.232834388315;6203.100550420074;6294.968525157528;6706.798796036127;6833.45332373399;6871.725130994704;6249.375062493714;6315.621062736251;6321.462675672619;6328.6202827830475;6336.809895703136;4466.330050492322;4501.279795232878;4559.065391594781;4580.083983203306;4625.7455839929125;4456.754878481585;4477.417935668343;4587.288970209489;4641.071785642817;4744.9051018978735 09:34:27 INFO - PROCESS | 9027 | |4;prototype.html;294.368579397193;301.1897994395077;302.08468340927;308.65430717596297;310.32039832766225;4870.537298956602;4886.462489126193;4887.437944636368;4904.288878112676;4938.432080310766;4831.5893149082285;4833.299171620121;4845.709257444547;4894.167991441454;4898.755062246892;3594.119440737023;3692.633725521547;3719.4420836874415;3770.6794922431627;3802.372608519591;3781.1870447853707;3794.392897136459;3796.7152463565203;3800.714946379019;3804.3152232598122;3780.9810950945207;3786.3752480840767;3789.46947427359;3792;3803.923921521568;3606.873759418421;3685.410334346505;3772.6038765929457;3775.9993601695573;3814.275287695329;3350.27968986669;3351.5307856900054;3356.6475520070285;3367.2928684976187;3375.3249350130072;4714.7642617869315;4724.763761811896;4740.525947405266;4746.691465054761;4749.24012158053;4194.370844373338;4300.6344460720875;4311.396404503372;4335.934960975588;4340.435743353375;4978.90042199154;4987.526185012418;4988.152014157566;5010.223415370623;5037.345145131146;3688.7233457490797;3703.2963736890174;3705.6664900158844;3709.1283548366114;3715.034091136297;5061.670991385548;5284.4187139414635;5290.867728306785;5305.257263983045;5327.946720532784;3760.2291530236234;3770.5098337215986;3788.9012186465616;3793.430985352164;3806.2006978534264;3822.235552889433;3827.2154208387215;3830.157365379612;3838.481804956295;3849.480320156799;4323.913521729548;4340.869773906725;4343.370211319341;4344.739315641072;4347.217500849814;4859.781309841065;4989.775460104304;5003.2995380646735;5084.262781896604;5087.465816089331;1293.792993121096;1311.1215485624655;1312.3635037007043;1312.7637025335532;1313.9868601313865;816.8611336072881;844.5523872347666;847.7753395350237;850.5449584472277;861.2378045429715;4280.6789490788315;4369.934450983238;4446.421965144576;4470.418845550058;4479.641628669699;5220.216967454912;5222.294990176278;5222.869428264324;5227.320448341691;5235.633505654644;4495.4605447345875;4502.549745025472;4502.57225563571;4503;4507.414036175276;2631.7762990146052;2743.43759529296;2764.032588594016;2764.346227621506;2775.5836624506487;4828.734419606885;4845.297431872359;4848.393950756155;4850.83022094225;4883.267509873547;5174.7153906535505;5348.572114230852;5370.62405631602;5376.784928602812;5400.432954539795;4794.376731025014;4805.663603547718;4833.806647734052;4846.224604063333;4854.271859221145;5226.581873450191;5232.607554433509;5237.685738855681;5249.13655652238;5264.605154613496;4981.2030075187795;5142.994232249351;5149.716765577929;5154.8969020620125;5162.793488260428;4822.662413631152;4824.903501930012;4828.251620998751;4845.466998630078;4846.079244943449;3771.849126034928;3785.9432108517844;3788.905277368088;3791.4691943128537;3795.6963442925053;4958;5225.973870130625;5230.555402790653;5239.26650268955;5272.815451459334;5060.974695126648;5077.416097148727;5082.2122571001555;5115.974420127875;5135.281060651437;5032.672876263177;5044.4451110377095;5058.317127187783;5087.956968821309;5115.846524604268;4844.588210002189;5002.224655178453;5015.297858299914;5030.798768049237;5048.343715316985;4835.896385072505;4961.900761984813;5085.694858308513;5085.872853178552;5094.561171209493;4975.154223781893;4979.950200498093;4982.302477653059;4987.925181122214;4998.300237966615 09:34:27 INFO - PROCESS | 9027 | |5;yui.html;594.0821430889275;594.5748789615424;595.9987221467934;691.3432239372597;715.7673756029292;12613.18014329068;13433.071799837922;13578.592642220725;13590.456381744718;13606.795898061524;13033.478660853578;13470.595882123527;13546.39041243144;13558.32208389579;13618.387172577233;767.1024900866;790.3163763344694;793.7698067560401;793.9325157361632;794.5153456391597;641.1664835713577;751.8270797716528;782.7103971530513;783.0916137280756;789.1122487201898;819.983600327993;820.3027426687328;820.4461988157999;820.7414664380731;821.3470291118546;815.6411179081185;815.8490679224333;816.0126247240846;816.1063635319346;819.19309480162;491.2336754662738;578.5920925747338;591.361329763854;594.8334466349429;598.6109029131056;13097.607071787766;13133.802992955114;13140.277284749334;13151.60545183646;13157;12350.938245308806;12503.312317822516;12559.120861539695;12678.619641410773;13214.339283035906;10815.459227038617;10999.945000275027;11031.779364412747;11282.153838462149;11365.829512557319;4016.176683779818;4080.979595102035;4114.958850411487;4198.85304014358;4203.453551038376;58877.41122588762;59526.40473595252;59673.701631492;59935.400645993854;63363.7327253457;9795.67128779682;9903.752406189758;9968.202543796478;9979.401235925867;10167.4916254187;19163.904180479007;19247.326343577908;19291.42125736202;19386.70919936202;19399;6441.5490915635455;6610.338966103352;6698.765543206062;6745.9662701686175;6766.526343156031;3580.3555360035443;3595.9280814383565;3607.644984657347;3612.548431446069;3614.1868079682386;2691.4078902641386;2750.944981100367;2762.1575419497244;2763.958540621892;2798.6921438641734;463.83302011275913;464.52850356887785;466.2190830359135;469.5773803576808;473.0893030915474;464.50530185352494;473.7110362678763;475.6884240822267;479.918413869643;495.1112752609075;7166.140063192451;7240.493165478471;7395.85208295831;7470.252974702486;7718.498297610638;387.9941800872989;436.1255682356854;511.57794819273505;563.5153767759723;579.325086274494;3531.099569609733;3761.830717617659;3922.7842468663926;4082.5713300103657;4191.7694526801315;93492;94638;95513;99268.50365748125;102120;81095;83713.58143209366;84290;84382.57808710917;88483.55758221168;73816.63091684507;73893;73953;74064.25935740682;74940.62529687317;6923.342282483156;7216.71133154689;7218.046611774607;7278.818029549304;7393.778186654409;41657.20942091698;41735.7913210432;43063.354049688656;43093.784531077144;43744;58331.4166858326;61392.69303653453;63084.68457657682;63406;66570.33429665834;9135.58889849945;9270.768230794283;9294.581743821409;9775.217982561237;9827.656032038843;9958;10274.33216840903;10674.039336459757;10705.464726763475;11106.833397498882;9582;9672.70981870545;9975.401475911469;10084;10091.899081009096;9869.358491698016;10014.849777253492;10097.394156350643;10133.18934485254;10414.062734353945;8637.740867773977;8739.694110706096;9351.205147562261;9357;9373.531323433937;7644.235576442311;7670.578118203553;8073.233042860918;8091.55496447701;8105.392095592736;7344.596047217455;7687.961560192163;8319.750407487785;8452.535110568977;8461 09:34:27 INFO - PROCESS | 9027 | __end_tp_report 09:34:27 INFO - PROCESS | 9027 | __start_cc_report 09:34:27 INFO - PROCESS | 9027 | _x_x_mozilla_cycle_collect,1019 09:34:27 INFO - PROCESS | 9027 | __end_cc_report 09:34:27 INFO - PROCESS | 9027 | __startTimestamp1469118867711__endTimestamp 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | ------- Summary: start ------- 09:34:27 INFO - PROCESS | 9027 | Number of tests: 6 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | [#0] dojo.html Cycles:165 Average:4608.69 Median:4739.62 stddev:479.70 (10.1%) stddev-sans-first:453.50 09:34:27 INFO - PROCESS | 9027 | Values: 2561.6 2569.4 2606.7 2618.7 2621.1 4543.6 4611.2 4675.7 4680.6 4698.7 4575.7 4603.0 4614.4 4628.1 4670.2 4189.8 4238.5 4244.6 4305.9 4452.0 4375.1 4403.8 4435.0 4447.4 4454.6 4206.2 4254.8 4259.5 4273.1 4368.1 4235.5 4245.8 4254.3 4308.2 4318.8 4214.7 4263.4 4280.5 4299.2 4332.4 4553.0 4663.4 4690.1 4719.4 4741.8 4942.0 4953.4 5012.0 5081.8 5083.3 5056.8 5057.4 5068.5 5090.0 5104.5 4566.1 4624.1 4760.8 4765.8 4772.0 5095.8 5157.1 5165.6 5169.4 5178.7 4893.4 4924.8 4924.9 4970.1 4980.2 4843.3 4914.1 4961.3 4992.7 5006.9 4838.7 4933.3 4933.5 4963.6 4964.7 4911.9 4918.3 4921.1 4923.9 4968.1 4142.3 4177.1 4184.7 4190.2 4241.1 3678.1 3696.0 3722.2 3761.1 3816.2 4344.4 4346.0 4346.7 4377.7 4387.9 4857.5 5042.1 5042.6 5047.4 5082.5 4238.4 4280.3 4326.7 4335.1 4373.4 4492.8 4520.9 4522.7 4525.1 4568.1 4632.6 4635.5 4641.9 4644.7 4685.9 4874.2 4952.6 4990.1 4990.3 5086.8 4636.4 4641.7 4666.5 4668.5 4719.3 4942.9 4949.6 4959.9 4962.6 5003.8 4859.8 4875.1 4889.8 4923.9 4986.6 4785.9 4807.5 4815.7 4819.1 4859.4 4789.2 4805.9 4806.3 4807.6 4854.9 4634.0 4739.6 4756.9 4770.8 4830.0 4851.3 4871.9 4884.8 4886.8 4931.8 4764.5 4773.8 4775.5 4790.8 4829.7 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | [#1] ext.html Cycles:180 Average:48936.58 Median:195222.50 stddev:89144.77 (45.7%) stddev-sans-first:89320.82 09:34:27 INFO - PROCESS | 9027 | Values: 550.0 574.7 577.1 577.9 580.3 27314.0 39377.6 39768.4 40081.4 44052.0 41026.2 41143.6 41426.0 41462.6 41464.8 7395.0 7429.5 7564.4 7564.5 7570.0 7551.0 7637.2 7639.8 9679.1 9919.7 10093.4 10110.8 10152.9 10158.6 10159.4 10061.1 10104.2 10121.0 10123.1 10127.7 10020.9 10113.1 10113.8 10115.7 10116.4 38627.8 38713.6 38767.4 38838.2 38847.0 2856.9 2911.2 2923.3 2929.0 2935.1 28031.3 28802.5 28837.7 28875.3 28992.7 3173.3 3231.6 3286.5 3356.6 3357.3 263320.0 263892.7 264586.0 264711.0 265964.0 53098.5 53495.5 53786.0 53847.2 53848.2 34899.8 35483.0 35523.8 57073.7 57166.1 14307.9 14327.3 14403.1 14424.1 14429.6 10893.6 10983.5 10993.5 10995.6 10997.9 16292.6 17760.9 18059.7 18150.8 18647.7 6552.4 7016.9 7055.6 7084.6 7211.9 795.3 876.8 911.3 944.3 969.4 27792.9 28021.0 28022.9 28354.6 28600.4 951.4 961.5 974.4 974.6 982.3 14451.6 14710.0 14927.4 15053.7 15291.7 381033.0 385504.0 385548.0 387358.0 388950.0 49904.3 50475.7 50495.7 50619.5 50686.5 1082.5 1161.3 1168.8 1194.5 1197.6 27418.6 28683.1 29013.4 29488.4 29612.3 46761.8 47908.0 47948.0 52865.5 52876.7 350994.0 353801.0 354962.0 355062.0 356038.0 24817.0 25760.7 25782.0 26750.3 26931.6 41351.4 41816.8 41837.8 42379.8 42828.4 35920.3 36005.8 36006.0 36384.0 36946.4 36531.5 36561.6 36803.0 37154.3 37764.0 35117.8 35570.8 35822.8 35897.6 36623.6 30734.0 32672.8 32953.0 33004.8 33064.5 24692.3 24944.8 25033.7 25230.7 25310.7 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | [#2] jquery.html Cycles:180 Average:23566.67 Median:5372.36 stddev:63021.75 (1173.1%) stddev-sans-first:63174.40 09:34:27 INFO - PROCESS | 9027 | Values: 333.2 360.4 362.3 364.4 384.0 12456.6 16059.2 16455.6 16649.6 17797.7 16471.3 16619.6 16743.5 17114.9 17212.3 4800.8 5015.8 5029.7 5287.9 5338.1 3432.1 3533.8 5406.6 5417.3 5438.5 3528.9 3654.5 3660.1 4720.4 5874.8 5862.9 5915.4 5925.9 5926.9 5928.1 5118.5 5242.0 5321.1 5333.5 5337.9 12427.6 12559.6 14754.7 20412.0 20794.6 1695.1 1823.5 1859.7 1925.0 1929.6 8847.1 8903.7 9136.4 9292.5 9428.4 1627.7 1717.3 1728.1 1773.2 1813.9 62711.0 65833.7 69785.7 71497.6 72607.6 9684.4 9712.0 9806.5 9818.2 10678.8 13623.3 13638.0 13649.2 13656.5 13679.5 6685.2 6715.6 6798.5 6895.3 7105.1 6482.2 6524.2 6742.5 6803.7 6839.1 4180.6 4197.2 4242.9 4250.9 4347.2 1741.2 1767.4 1775.4 1810.7 1826.4 785.0 822.5 860.1 874.1 929.2 16086.0 16756.7 17043.7 17468.6 17555.6 819.4 822.4 830.7 845.3 881.0 9131.3 9385.8 9583.4 9587.8 9657.6 372031.0 375353.0 383863.0 385358.0 386598.0 77714.0 78102.6 78967.2 79140.0 80270.0 876.9 879.7 897.8 899.3 916.6 14336.2 14853.7 14952.9 14953.9 14980.6 33944.8 34993.5 35284.6 35511.0 36859.8 49166.5 49682.8 50088.2 50810.7 51436.0 7157.4 7241.5 7245.6 7250.3 7544.1 8843.4 10424.8 10444.4 10675.2 10880.6 8734.1 8884.1 8977.8 9003.3 9048.5 9168.1 9225.9 9358.0 9381.9 9522.1 7772.0 8929.2 8930.7 9105.0 9605.8 7138.4 7200.2 7264.2 7299.3 7562.0 7428.7 8883.6 9048.9 9127.1 9247.3 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | [#3] mootools.html Cycles:180 Average:5190.50 Median:4740.43 stddev:2982.51 (62.9%) stddev-sans-first:2976.36 09:34:27 INFO - PROCESS | 9027 | Values: 1274.4 1341.8 1424.5 1454.5 1684.0 6772.9 7749.0 8006.0 8009.4 8260.5 8027.0 8101.0 8110.3 8113.8 8124.6 2150.2 2167.5 2171.8 2171.9 2178.3 2087.6 2123.0 2169.9 2176.7 2181.9 2177.0 2200.5 2214.4 2218.6 2220.8 2120.5 2120.9 2128.0 2128.8 2132.3 2083.8 2114.2 2121.2 2127.1 2127.9 7939.5 8015.1 8068.0 8112.4 8121.9 4537.3 4735.9 4737.2 4743.7 4749.8 2787.6 2916.4 2956.9 2959.2 2970.0 828.2 840.0 841.0 1615.4 1648.8 10613.9 11113.0 11182.2 11213.2 11221.0 4131.3 4242.9 4331.1 4424.3 4430.1 9422.9 9425.9 9428.5 9429.1 9447.4 4752.1 4757.4 4760.3 4762.8 4762.9 3760.2 4185.2 4344.5 4356.6 4364.1 3645.7 3673.2 3818.8 3823.1 3843.6 1166.3 1201.7 1206.8 1213.1 1215.9 746.8 775.0 787.9 787.9 788.8 4951.9 4991.2 4999.9 5032.5 5260.2 390.1 395.9 408.9 410.0 411.5 3272.9 3294.8 3373.5 3404.4 3542.9 9649.7 9984.8 10063.5 10099.5 10104.3 9391.6 10011.5 10022.6 10031.2 10050.8 7133.1 7308.4 7312.6 7313.2 7315.8 5058.0 5119.1 5152.2 5170.3 5373.9 8857.0 8863.3 8871.1 8877.0 8971.4 8830.2 8989.7 9171.9 9183.9 9436.9 5733.8 6780.4 6974.5 7121.4 7135.4 7565.6 7693.7 7756.8 7757.2 7766.7 6649.0 6764.9 6811.1 6893.4 6974.2 6203.1 6295.0 6706.8 6833.5 6871.7 6249.4 6315.6 6321.5 6328.6 6336.8 4466.3 4501.3 4559.1 4580.1 4625.7 4456.8 4477.4 4587.3 4641.1 4744.9 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | [#4] prototype.html Cycles:180 Average:4215.55 Median:4830.16 stddev:1215.82 (25.2%) stddev-sans-first:1183.07 09:34:27 INFO - PROCESS | 9027 | Values: 294.4 301.2 302.1 308.7 310.3 4870.5 4886.5 4887.4 4904.3 4938.4 4831.6 4833.3 4845.7 4894.2 4898.8 3594.1 3692.6 3719.4 3770.7 3802.4 3781.2 3794.4 3796.7 3800.7 3804.3 3781.0 3786.4 3789.5 3792.0 3803.9 3606.9 3685.4 3772.6 3776.0 3814.3 3350.3 3351.5 3356.6 3367.3 3375.3 4714.8 4724.8 4740.5 4746.7 4749.2 4194.4 4300.6 4311.4 4335.9 4340.4 4978.9 4987.5 4988.2 5010.2 5037.3 3688.7 3703.3 3705.7 3709.1 3715.0 5061.7 5284.4 5290.9 5305.3 5327.9 3760.2 3770.5 3788.9 3793.4 3806.2 3822.2 3827.2 3830.2 3838.5 3849.5 4323.9 4340.9 4343.4 4344.7 4347.2 4859.8 4989.8 5003.3 5084.3 5087.5 1293.8 1311.1 1312.4 1312.8 1314.0 816.9 844.6 847.8 850.5 861.2 4280.7 4369.9 4446.4 4470.4 4479.6 5220.2 5222.3 5222.9 5227.3 5235.6 4495.5 4502.5 4502.6 4503.0 4507.4 2631.8 2743.4 2764.0 2764.3 2775.6 4828.7 4845.3 4848.4 4850.8 4883.3 5174.7 5348.6 5370.6 5376.8 5400.4 4794.4 4805.7 4833.8 4846.2 4854.3 5226.6 5232.6 5237.7 5249.1 5264.6 4981.2 5143.0 5149.7 5154.9 5162.8 4822.7 4824.9 4828.3 4845.5 4846.1 3771.8 3785.9 3788.9 3791.5 3795.7 4958.0 5226.0 5230.6 5239.3 5272.8 5061.0 5077.4 5082.2 5116.0 5135.3 5032.7 5044.4 5058.3 5088.0 5115.8 4844.6 5002.2 5015.3 5030.8 5048.3 4835.9 4961.9 5085.7 5085.9 5094.6 4975.2 4980.0 4982.3 4987.9 4998.3 09:34:27 INFO - PROCESS | 9027 | 09:34:27 INFO - PROCESS | 9027 | [#5] yui.html Cycles:180 Average:17236.18 Median:30061.20 stddev:25254.23 (84.0%) stddev-sans-first:25294.16 09:34:27 INFO - PROCESS | 9027 | Values: 594.1 594.6 596.0 691.3 715.8 12613.2 13433.1 13578.6 13590.5 13606.8 13033.5 13470.6 13546.4 13558.3 13618.4 767.1 790.3 793.8 793.9 794.5 641.2 751.8 782.7 783.1 789.1 820.0 820.3 820.4 820.7 821.3 815.6 815.8 816.0 816.1 819.2 491.2 578.6 591.4 594.8 598.6 13097.6 13133.8 13140.3 13151.6 13157.0 12350.9 12503.3 12559.1 12678.6 13214.3 10815.5 10999.9 11031.8 11282.2 11365.8 4016.2 4081.0 4115.0 4198.9 4203.5 58877.4 59526.4 59673.7 59935.4 63363.7 9795.7 9903.8 9968.2 9979.4 10167.5 19163.9 19247.3 19291.4 19386.7 19399.0 6441.5 6610.3 6698.8 6746.0 6766.5 3580.4 3595.9 3607.6 3612.5 3614.2 2691.4 2750.9 2762.2 2764.0 2798.7 463.8 464.5 466.2 469.6 473.1 464.5 473.7 475.7 479.9 495.1 7166.1 7240.5 7395.9 7470.3 7718.5 388.0 436.1 511.6 563.5 579.3 3531.1 3761.8 3922.8 4082.6 4191.8 93492.0 94638.0 95513.0 99268.5 102120.0 81095.0 83713.6 84290.0 84382.6 88483.6 73816.6 73893.0 73953.0 74064.3 74940.6 6923.3 7216.7 7218.0 7278.8 7393.8 41657.2 41735.8 43063.4 43093.8 43744.0 58331.4 61392.7 63084.7 63406.0 66570.3 9135.6 9270.8 9294.6 9775.2 9827.7 9958.0 10274.3 10674.0 10705.5 11106.8 9582.0 9672.7 9975.4 10084.0 10091.9 9869.4 10014.8 10097.4 10133.2 10414.1 8637.7 8739.7 9351.2 9357.0 9373.5 7644.2 7670.6 8073.2 8091.6 8105.4 7344.6 7688.0 8319.8 8452.5 8461.0 09:34:27 INFO - PROCESS | 9027 | -------- Summary: end -------- 09:34:27 INFO - PROCESS | 9027 | 09:34:28 INFO - TEST-INFO | 9027: exit 0 09:34:28 INFO - TEST-OK | dromaeo_css | took 1118801ms 09:34:28 INFO - TEST-START | kraken 09:34:28 INFO - operating with platform_type : linux_ 09:34:28 INFO - Initialising browser for kraken test... 09:34:28 INFO - TEST-INFO | started process 9220 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC4Tjwf/profile http://localhost:40532/getInfo.html) 09:34:31 INFO - PROCESS | 9220 | JavaScript error: , line 0: uncaught exception: undefined 09:34:32 INFO - PROCESS | 9220 | __metrics Screen width/height:1600/1200 09:34:32 INFO - PROCESS | 9220 | colorDepth:24 09:34:32 INFO - PROCESS | 9220 | Browser inner width/height: 1024/697 09:34:32 INFO - PROCESS | 9220 | __metrics 09:34:33 INFO - PROCESS | 9220 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:34:33 INFO - PROCESS | 9220 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:34:34 INFO - TEST-INFO | 9220: exit 0 09:34:34 INFO - Browser initialized. 09:34:34 INFO - Running cycle 1/1 for kraken test... 09:34:34 INFO - TEST-INFO | started process 9300 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC4Tjwf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:46 INFO - PROCESS | 9300 | RSS: Main: 159854592 09:34:46 INFO - PROCESS | 9300 | 09:34:49 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40532/tests/kraken/driver.html?testName=audio-beat-detection) 09:34:50 INFO - PROCESS | 9300 | RSS: Main: 215875584 09:34:50 INFO - PROCESS | 9300 | 09:34:52 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40532/tests/kraken/driver.html?testName=audio-dft) 09:34:53 INFO - PROCESS | 9300 | RSS: Main: 195035136 09:34:53 INFO - PROCESS | 9300 | 09:34:56 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40532/tests/kraken/driver.html?testName=audio-fft) 09:34:57 INFO - PROCESS | 9300 | RSS: Main: 273801216 09:34:57 INFO - PROCESS | 9300 | 09:34:59 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40532/tests/kraken/driver.html?testName=audio-oscillator) 09:35:00 INFO - PROCESS | 9300 | RSS: Main: 181780480 09:35:00 INFO - PROCESS | 9300 | 09:35:02 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40532/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:03 INFO - PROCESS | 9300 | RSS: Main: 187383808 09:35:03 INFO - PROCESS | 9300 | 09:35:06 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40532/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:06 INFO - PROCESS | 9300 | RSS: Main: 223404032 09:35:06 INFO - PROCESS | 9300 | 09:35:10 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40532/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:10 INFO - PROCESS | 9300 | RSS: Main: 222519296 09:35:10 INFO - PROCESS | 9300 | 09:35:13 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40532/tests/kraken/driver.html?testName=json-parse-financial) 09:35:14 INFO - PROCESS | 9300 | RSS: Main: 222973952 09:35:14 INFO - PROCESS | 9300 | 09:35:16 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40532/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:16 INFO - PROCESS | 9300 | RSS: Main: 186503168 09:35:16 INFO - PROCESS | 9300 | 09:35:18 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:35:19 INFO - PROCESS | 9300 | RSS: Main: 192397312 09:35:19 INFO - PROCESS | 9300 | 09:35:21 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:35:22 INFO - PROCESS | 9300 | RSS: Main: 196780032 09:35:22 INFO - PROCESS | 9300 | 09:35:24 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:35:25 INFO - PROCESS | 9300 | RSS: Main: 191537152 09:35:25 INFO - PROCESS | 9300 | 09:35:28 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:35:28 INFO - PROCESS | 9300 | RSS: Main: 194985984 09:35:28 INFO - PROCESS | 9300 | 09:35:30 INFO - PROCESS | 9300 | Cycle 1(1): loaded http://localhost:40532/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40532/tests/kraken/driver.html?testName=ai-astar) 09:35:30 INFO - PROCESS | 9300 | RSS: Main: 245579776 09:35:30 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | __start_tp_report 09:35:31 INFO - PROCESS | 9300 | _x_x_mozilla_page_load 09:35:31 INFO - PROCESS | 9300 | _x_x_mozilla_page_load_details 09:35:31 INFO - PROCESS | 9300 | |i|pagename|runs| 09:35:31 INFO - PROCESS | 9300 | |0;ai-astar;106;115;112;113;112;113;112;113;112;111 09:35:31 INFO - PROCESS | 9300 | |1;audio-beat-detection;146;123;124;188;122;128;148;122;157;125 09:35:31 INFO - PROCESS | 9300 | |2;audio-dft;161;161;160;163;161;161;162;162;162;162 09:35:31 INFO - PROCESS | 9300 | |3;audio-fft;75;76;76;129;76;77;75;76;106;76 09:35:31 INFO - PROCESS | 9300 | |4;audio-oscillator;86;86;114;85;86;86;116;85;86;135 09:35:31 INFO - PROCESS | 9300 | |5;imaging-gaussian-blur;118;116;117;117;117;119;117;117;117;117 09:35:31 INFO - PROCESS | 9300 | |6;imaging-darkroom;147;149;146;146;146;147;146;146;146;147 09:35:31 INFO - PROCESS | 9300 | |7;imaging-desaturate;95;95;96;95;95;94;95;95;95;95 09:35:31 INFO - PROCESS | 9300 | |8;json-parse-financial;62;62;61;61;62;61;61;60;61;61 09:35:31 INFO - PROCESS | 9300 | |9;json-stringify-tinderbox;66;65;65;66;66;66;66;66;65;66 09:35:31 INFO - PROCESS | 9300 | |10;stanford-crypto-aes;83;82;81;85;81;79;79;80;80;83 09:35:31 INFO - PROCESS | 9300 | |11;stanford-crypto-ccm;130;133;133;139;135;137;133;130;174;152 09:35:31 INFO - PROCESS | 9300 | |12;stanford-crypto-pbkdf2;164;163;165;168;164;164;161;167;163;165 09:35:31 INFO - PROCESS | 9300 | |13;stanford-crypto-sha256-iterative;76;67;67;65;67;66;66;66;65;65 09:35:31 INFO - PROCESS | 9300 | __end_tp_report 09:35:31 INFO - PROCESS | 9300 | __start_cc_report 09:35:31 INFO - PROCESS | 9300 | _x_x_mozilla_cycle_collect,1907 09:35:31 INFO - PROCESS | 9300 | __end_cc_report 09:35:31 INFO - PROCESS | 9300 | __startTimestamp1469118931000__endTimestamp 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | ------- Summary: start ------- 09:35:31 INFO - PROCESS | 9300 | Number of tests: 14 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.50 stddev:2.33 (2.1%) stddev-sans-first:1.13 09:35:31 INFO - PROCESS | 9300 | Values: 106.0 115.0 112.0 113.0 112.0 113.0 112.0 113.0 112.0 111.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:137.00 stddev:21.67 (15.8%) stddev-sans-first:22.80 09:35:31 INFO - PROCESS | 9300 | Values: 146.0 123.0 124.0 188.0 122.0 128.0 148.0 122.0 157.0 125.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.85 (0.5%) stddev-sans-first:0.88 09:35:31 INFO - PROCESS | 9300 | Values: 161.0 161.0 160.0 163.0 161.0 161.0 162.0 162.0 162.0 162.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#3] audio-fft Cycles:10 Average:84.20 Median:76.00 stddev:18.38 (24.2%) stddev-sans-first:19.19 09:35:31 INFO - PROCESS | 9300 | Values: 75.0 76.0 76.0 129.0 76.0 77.0 75.0 76.0 106.0 76.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:86.00 stddev:18.21 (21.2%) stddev-sans-first:18.91 09:35:31 INFO - PROCESS | 9300 | Values: 86.0 86.0 114.0 85.0 86.0 86.0 116.0 85.0 86.0 135.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.79 (0.7%) stddev-sans-first:0.78 09:35:31 INFO - PROCESS | 9300 | Values: 118.0 116.0 117.0 117.0 117.0 119.0 117.0 117.0 117.0 117.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:146.50 stddev:0.97 (0.7%) stddev-sans-first:1.01 09:35:31 INFO - PROCESS | 9300 | Values: 147.0 149.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 09:35:31 INFO - PROCESS | 9300 | Values: 95.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 95.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 09:35:31 INFO - PROCESS | 9300 | Values: 62.0 62.0 61.0 61.0 62.0 61.0 61.0 60.0 61.0 61.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 09:35:31 INFO - PROCESS | 9300 | Values: 66.0 65.0 65.0 66.0 66.0 66.0 66.0 66.0 65.0 66.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.50 stddev:1.95 (2.4%) stddev-sans-first:1.96 09:35:31 INFO - PROCESS | 9300 | Values: 83.0 82.0 81.0 85.0 81.0 79.0 79.0 80.0 80.0 83.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:136.00 stddev:13.66 (10.0%) stddev-sans-first:14.04 09:35:31 INFO - PROCESS | 9300 | Values: 130.0 133.0 133.0 139.0 135.0 137.0 133.0 130.0 174.0 152.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.40 Median:164.50 stddev:2.01 (1.2%) stddev-sans-first:2.13 09:35:31 INFO - PROCESS | 9300 | Values: 164.0 163.0 165.0 168.0 164.0 164.0 161.0 167.0 163.0 165.0 09:35:31 INFO - PROCESS | 9300 | 09:35:31 INFO - PROCESS | 9300 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:66.50 stddev:3.27 (4.9%) stddev-sans-first:0.87 09:35:31 INFO - PROCESS | 9300 | Values: 76.0 67.0 67.0 65.0 67.0 66.0 66.0 66.0 65.0 65.0 09:35:31 INFO - PROCESS | 9300 | -------- Summary: end -------- 09:35:31 INFO - PROCESS | 9300 | 09:35:32 INFO - TEST-INFO | 9300: exit 0 09:35:32 INFO - TEST-OK | kraken | took 63539ms 09:35:32 INFO - SUITE-END | took 1182s 09:35:32 INFO - Completed test suite (00:19:42) 09:35:32 INFO - javascript benchmark 09:35:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2561.628563858241, 2569.3833479964815, 2606.7393260673934, 2618.6988496322915, 2621.1219241554068, 4543.568361005708, 4611.19304121779, 4675.742834144121, 4680.5787479126875, 4698.694584851983, 4575.725456472605, 4602.9769851150695, 4614.4462664480225, 4628.120657075154, 4670.229412147, 4189.811458484375, 4238.533761286256, 4244.554321796213, 4305.892352691192, 4452.0, 4375.124975004996, 4403.779811009453, 4435.0, 4447.4218351614245, 4454.621357184627, 4206.242876282268, 4254.7872606369865, 4259.467566554181, 4273.145370925828, 4368.104538569585, 4235.4917409911, 4245.766482843443, 4254.31930891056, 4308.246056940023, 4318.848840290575, 4214.662826973835, 4263.445752052245, 4280.5291417944, 4299.247631664446, 4332.371806088131, 4553.043860789238, 4663.370444989917, 4690.132325519789, 4719.4100737407825, 4741.786619602091, 4942.0, 4953.405591329064, 5012.022655582163, 5081.79672813087, 5083.3391659084455, 5056.82301119459, 5057.443681195065, 5068.543831055223, 5090.032893750175, 5104.51507106828, 4566.06395688883, 4624.075184962953, 4760.809567617326, 4765.833195838199, 4772.045590881838, 5095.821646242363, 5157.149070403417, 5165.638405311667, 5169.379674439091, 5178.663386879841, 4893.437254715682, 4924.75376231187, 4924.8768780780765, 4970.080535101018, 4980.203167493184, 4843.346148270009, 4914.140025495524, 4961.280614310905, 4992.700437973734, 5006.949930500721, 4838.685485443505, 4933.309336692866, 4933.506649335038, 4963.57809586182, 4964.677295975822, 4911.901761964812, 4918.336024636628, 4921.064997650435, 4923.87690307738, 4968.130577148984, 4142.337226043818, 4177.081042170778, 4184.66522678185, 4190.224808410454, 4241.1093670328955, 3678.0988657779017, 3695.981520092382, 3722.181120153562, 3761.1161377076364, 3816.160444702161, 4344.391785150082, 4346.0, 4346.695731298842, 4377.737335759864, 4387.859156619238, 4857.489963553847, 5042.142835717937, 5042.596592272609, 5047.369078865142, 5082.491750824888, 4238.4490016298305, 4280.272353699878, 4326.6754993375625, 4335.089631177425, 4373.431453910971, 4492.752898590609, 4520.864374068783, 4522.66080043998, 4525.094981003813, 4568.063546972929, 4632.606228470618, 4635.536446355405, 4641.86074417768, 4644.674872758875, 4685.898813778759, 4874.171390863633, 4952.5542701157065, 4990.101781679333, 4990.301357809837, 5086.771095300646, 4636.397268355092, 4641.721496710208, 4666.463356714022, 4668.486466488615, 4719.2921061839625, 4942.901141977212, 4949.554540091409, 4959.851204463872, 4962.578180854668, 5003.774075351416, 4859.829905953275, 4875.07373599015, 4889.755512224446, 4923.876903077452, 4986.6010719143105, 4785.880352991203, 4807.519248075165, 4815.686980346266, 4819.132556139928, 4859.441164266154, 4789.185838407409, 4805.8558243252755, 4806.255030470283, 4807.6153907688, 4854.8786280341865, 4633.976830115827, 4739.620830333496, 4756.904861902812, 4770.761461926959, 4830.033993201304, 4851.32081508582, 4871.853844384674, 4884.804607815648, 4886.780094895809, 4931.802727890844, 4764.547368000034, 4773.785179666854, 4775.498572649822, 4790.8323208687525, 4829.710217386968], "value": 4578.170782064805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.9697516636585, 574.7126436781609, 577.0651544497913, 577.904645733454, 580.3210244014502, 27314.0, 39377.60622393775, 39768.40347394792, 40081.39877901834, 44052.0, 41026.17947641053, 41143.58856411435, 41426.0, 41462.58537414625, 41464.79267603658, 7395.0, 7429.517081389719, 7564.394848412141, 7564.546127232356, 7570.0, 7550.962245188766, 7637.236276372374, 7639.809004774897, 9679.128878400943, 9919.652812151577, 10093.394396336244, 10110.797784044315, 10152.949235253813, 10158.593656253777, 10159.441230732307, 10061.144802691744, 10104.242181836391, 10121.03850134236, 10123.139533139693, 10127.696169114939, 10020.89979100207, 10113.140383067448, 10113.848292275621, 10115.696529104138, 10116.393016419039, 38627.80686096579, 38713.61286387157, 38767.418488722695, 38838.22323553541, 38847.028824279616, 2856.9142925712054, 2911.213972227487, 2923.3422479942055, 2929.0, 2935.148806846012, 28031.29921751952, 28802.450595243983, 28837.711622883922, 28875.27811804701, 28992.710072899423, 3173.254285242966, 3231.6104075247636, 3286.5398844161837, 3356.63077061523, 3357.2949680567112, 263320.0, 263892.680536598, 264586.0, 264711.0, 265964.0, 53098.469015310126, 53495.465045349825, 53786.0, 53847.19229211565, 53848.19227711587, 34899.82550087284, 35483.0, 35523.82238088793, 57073.71463142658, 57166.142507862416, 14307.92846035763, 14327.283635818167, 14403.135811851322, 14424.06243594163, 14429.639259018399, 10893.564257429773, 10983.505742241618, 10993.505292261867, 10995.615153469591, 10997.890021099847, 16292.592685182964, 17760.91119544394, 18059.729104063448, 18150.818491814913, 18647.720284195748, 6552.3775241352, 7016.894746578805, 7055.611941343275, 7084.610346430893, 7211.855762884713, 795.3478147918767, 876.8421684096924, 911.3392790227165, 944.2776276148752, 969.4377261188494, 27792.861035694692, 28021.0, 28022.85988570044, 28354.574681379796, 28600.427991440054, 951.438651195784, 961.4615815143402, 974.4238545310129, 974.5565767575736, 982.2976571751232, 14451.638709032359, 14710.0, 14927.40290388394, 15053.698926021638, 15291.694166116615, 381033.0, 385504.0, 385548.0, 387358.0, 388950.0, 49904.251436228486, 50475.74762126166, 50495.74752126289, 50619.493805062215, 50686.493135068915, 1082.4912291223113, 1161.2742036227357, 1168.7779321928977, 1194.4624918786408, 1197.5628895453272, 27418.5887211688, 28683.13950581486, 29013.41973160567, 29488.41023179567, 29612.259693507836, 46761.76619116883, 47908.0, 47948.0, 52865.47134528605, 52876.735616321675, 350994.0, 353801.0, 354962.0, 355062.0, 356038.0, 24817.0, 25760.742392575834, 25782.0, 26750.331241719112, 26931.596026059233, 41351.37972930348, 41816.790916045225, 41837.79081104575, 42379.7881010593, 42828.35757463578, 35920.28159436744, 36005.819970899975, 36006.0, 36384.0, 36946.44580331351, 36531.452028219064, 36561.63438365582, 36803.0, 37154.25691486101, 37764.0, 35117.82441087778, 35570.822145889106, 35822.8208858954, 35897.64102358943, 36623.63376366202, 30734.0, 32672.836635816668, 32953.0, 33004.83497582593, 33064.50403243905, 24692.259232223063, 24944.750552494243, 25033.74966250314, 25230.74769252284, 25310.746892531577], "value": 17944.24249995889, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.1687439837606, 360.38914040701025, 362.3495824994136, 364.3932851801749, 384.0494775430809, 12456.564020259293, 16059.19704014799, 16455.588610284747, 16649.583760405996, 17797.73303400447, 16471.258793354296, 16619.584510387245, 16743.497695069167, 17114.914425427854, 17212.31150753968, 4800.759962001904, 5015.799368025275, 5029.698218106907, 5287.86780330491, 5338.119210330291, 3432.1076520104702, 3533.805640689761, 5406.594285485782, 5417.322834645669, 5438.456154384569, 3528.894133176009, 3654.506641603376, 3660.103274697703, 4720.409948756405, 5874.76500939964, 5862.853428664275, 5915.408459154093, 5925.940740592603, 5926.940730592682, 5928.140419639129, 5118.539331460149, 5242.0, 5321.068812957717, 5333.519983201491, 5337.919931201035, 12427.565035223814, 12559.560415385418, 14754.70490590193, 20412.0, 20794.5841083179, 1695.1100672147222, 1823.525883270346, 1859.6745569525415, 1925.0, 1929.556202073531, 8847.07105753893, 8903.705831942152, 9136.406133601295, 9292.53537323131, 9428.434293942386, 1627.6581917797278, 1717.3474079849702, 1728.08411541883, 1773.2375078716148, 1813.8730288879783, 62711.0, 65833.67083164601, 69785.65107174483, 71497.64251178764, 72607.63696181485, 9684.370515916444, 9712.0, 9806.53603842516, 9818.165455936325, 10678.839817402744, 13623.318834058258, 13638.0, 13649.249291288876, 13656.522021729192, 13679.452821887213, 6685.164354455693, 6715.597064176165, 6798.456123510107, 6895.275996020446, 7105.111861017373, 6482.222133343935, 6524.184476940382, 6742.49431292655, 6803.693833777492, 6839.076724642209, 4180.581941805856, 4197.244495990751, 4242.915141697211, 4250.893727656833, 4347.152305300436, 1741.2116679132387, 1767.3814165042133, 1775.4052392448416, 1810.710286354177, 1826.3972888946616, 784.9803754906173, 822.4695071678734, 860.0582362313257, 874.0560194989396, 929.1683942871227, 16086.0, 16756.664866702842, 17043.65912681764, 17468.563285917953, 17555.561110972074, 819.4100247821559, 822.43663090783, 830.7009476588423, 845.2815107158866, 880.9691660791841, 9131.315151363675, 9385.765355866022, 9583.377080489747, 9587.760305992406, 9657.565409556446, 372031.0, 375353.0, 383863.0, 385358.0, 386598.0, 77714.0, 78102.6094869522, 78967.21032789713, 79140.0, 80270.0, 876.8903887014123, 879.6525372477963, 897.82043591282, 899.2895612466078, 916.5554705967601, 14336.222457595164, 14853.702925941205, 14952.925235373754, 14953.865733375616, 14980.625484362978, 33944.830275848464, 34993.475097873044, 35284.64715352814, 35511.0, 36859.815700922394, 49166.508334916194, 49682.75158624184, 50088.24867627062, 50810.745946270035, 51436.0, 7157.355837974607, 7241.493095483265, 7245.5652660840515, 7250.3112204340505, 7544.132424771222, 8843.380963332762, 10424.791504170025, 10444.425556594157, 10675.199360047873, 10880.564777408816, 8734.126587341216, 8884.111588841064, 8977.775555611162, 9003.324750643596, 9048.457092574467, 9168.129027742354, 9225.907740922505, 9358.0, 9381.859272110787, 9522.14300712939, 7771.9896413465885, 8929.196372326522, 8930.732078037669, 9105.043970383014, 9605.759856003655, 7138.393236574741, 7200.1719802223415, 7264.164621068645, 7299.343059124704, 7562.0, 7428.739994100397, 8883.555822208735, 9048.909510904807, 9127.08729127082, 9247.260219182584], "value": 7805.118170463653, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1274.4010315151875, 1341.8456877459091, 1424.5014245014245, 1454.483658301303, 1684.0400971446281, 6772.932270677292, 7749.0, 8006.0, 8009.439339246248, 8260.50436973782, 8026.959865200681, 8101.02787665481, 8110.270075693186, 8113.797155071112, 8124.634391452384, 2150.236665983573, 2167.4906396996694, 2171.826253899684, 2171.934841954736, 2178.2920550821045, 2087.5929193807215, 2123.002188971181, 2169.9240526581575, 2176.706144670473, 2181.923632672857, 2177.0203408466173, 2200.526886719355, 2214.446388402899, 2218.5562887422498, 2220.8334374921947, 2120.544083022158, 2120.8621439606377, 2127.95744085119, 2128.755193152784, 2132.3283165802723, 2083.8228750556223, 2114.164904862586, 2121.1833444123968, 2127.149140343875, 2127.936161915145, 7939.523628582304, 8015.118336982927, 8067.95966020172, 8112.351011919032, 8121.878171827428, 4537.251353526664, 4735.857924262277, 4737.170995075882, 4743.691660042121, 4749.762511874393, 2787.6236708044357, 2916.387558612673, 2956.9408611827644, 2959.1566403575093, 2970.0347387099278, 828.1511450762956, 839.9790005249856, 840.9621567029499, 1615.4184493582418, 1648.785657864482, 10613.893861061444, 11113.0, 11182.16133789969, 11213.15901307397, 11221.04621107207, 4131.338985762323, 4242.8727138185905, 4331.068820203672, 4424.3142312941545, 4430.13612345596, 9422.858657120149, 9425.905740942502, 9428.481433521221, 9429.104235097655, 9447.385919915183, 4752.097101550694, 4757.35775670287, 4760.285957106462, 4762.809487620457, 4762.857114286577, 3760.2291530236234, 4185.24665560196, 4344.543822898613, 4356.607905288539, 4364.061726728769, 3645.7265705072227, 3673.173535954389, 3818.8281527331337, 3823.0824602095295, 3843.615638436134, 1166.3177041430688, 1201.7296108375544, 1206.8250103734908, 1213.096243298748, 1215.8723334049973, 746.8095635612884, 774.9612519374009, 787.8576064706199, 787.8739401695816, 788.7949133225403, 4951.925721114186, 4991.153273288906, 4999.900001999939, 5032.547070763649, 5260.237265596467, 390.0852500885419, 395.89939966892086, 408.93457046872356, 410.03436906086336, 411.4527678188003, 3272.869085236564, 3294.802311861248, 3373.5323541561725, 3404.3701915145284, 3542.875999340011, 9649.710508684751, 9984.750381240381, 10063.496825158712, 10099.495025248709, 10104.292699510963, 9391.577379017825, 10011.549480273405, 10022.599096036078, 10031.197504199647, 10050.798984020279, 7133.144022717307, 7308.415326773846, 7312.561246325237, 7313.158986716596, 7315.780526584211, 5058.0, 5119.104156772475, 5152.227165924991, 5170.302009228706, 5373.865653358697, 8857.0, 8863.290936724918, 8871.068537803696, 8877.023527412108, 8971.416857904216, 8830.249428798625, 8989.685361012333, 9171.862422063808, 9183.862242066241, 9436.905630943602, 5733.770649173986, 6780.423663988419, 6974.476914231352, 7121.394681452135, 7135.429165666838, 7565.583892885724, 7693.730719424793, 7756.8448631028195, 7757.224277572198, 7766.728164514215, 6648.966755166193, 6764.864702706017, 6811.080504131879, 6893.448524118159, 6974.232834388315, 6203.100550420074, 6294.968525157528, 6706.798796036127, 6833.45332373399, 6871.725130994704, 6249.375062493714, 6315.621062736251, 6321.462675672619, 6328.6202827830475, 6336.809895703136, 4466.330050492322, 4501.279795232878, 4559.065391594781, 4580.083983203306, 4625.7455839929125, 4456.754878481585, 4477.417935668343, 4587.288970209489, 4641.071785642817, 4744.9051018978735], "value": 4092.2408919014256, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.368579397193, 301.1897994395077, 302.08468340927, 308.65430717596297, 310.32039832766225, 4870.537298956602, 4886.462489126193, 4887.437944636368, 4904.288878112676, 4938.432080310766, 4831.5893149082285, 4833.299171620121, 4845.709257444547, 4894.167991441454, 4898.755062246892, 3594.119440737023, 3692.633725521547, 3719.4420836874415, 3770.6794922431627, 3802.372608519591, 3781.1870447853707, 3794.392897136459, 3796.7152463565203, 3800.714946379019, 3804.3152232598122, 3780.9810950945207, 3786.3752480840767, 3789.46947427359, 3792.0, 3803.923921521568, 3606.873759418421, 3685.410334346505, 3772.6038765929457, 3775.9993601695573, 3814.275287695329, 3350.27968986669, 3351.5307856900054, 3356.6475520070285, 3367.2928684976187, 3375.3249350130072, 4714.7642617869315, 4724.763761811896, 4740.525947405266, 4746.691465054761, 4749.24012158053, 4194.370844373338, 4300.6344460720875, 4311.396404503372, 4335.934960975588, 4340.435743353375, 4978.90042199154, 4987.526185012418, 4988.152014157566, 5010.223415370623, 5037.345145131146, 3688.7233457490797, 3703.2963736890174, 3705.6664900158844, 3709.1283548366114, 3715.034091136297, 5061.670991385548, 5284.4187139414635, 5290.867728306785, 5305.257263983045, 5327.946720532784, 3760.2291530236234, 3770.5098337215986, 3788.9012186465616, 3793.430985352164, 3806.2006978534264, 3822.235552889433, 3827.2154208387215, 3830.157365379612, 3838.481804956295, 3849.480320156799, 4323.913521729548, 4340.869773906725, 4343.370211319341, 4344.739315641072, 4347.217500849814, 4859.781309841065, 4989.775460104304, 5003.2995380646735, 5084.262781896604, 5087.465816089331, 1293.792993121096, 1311.1215485624655, 1312.3635037007043, 1312.7637025335532, 1313.9868601313865, 816.8611336072881, 844.5523872347666, 847.7753395350237, 850.5449584472277, 861.2378045429715, 4280.6789490788315, 4369.934450983238, 4446.421965144576, 4470.418845550058, 4479.641628669699, 5220.216967454912, 5222.294990176278, 5222.869428264324, 5227.320448341691, 5235.633505654644, 4495.4605447345875, 4502.549745025472, 4502.57225563571, 4503.0, 4507.414036175276, 2631.7762990146052, 2743.43759529296, 2764.032588594016, 2764.346227621506, 2775.5836624506487, 4828.734419606885, 4845.297431872359, 4848.393950756155, 4850.83022094225, 4883.267509873547, 5174.7153906535505, 5348.572114230852, 5370.62405631602, 5376.784928602812, 5400.432954539795, 4794.376731025014, 4805.663603547718, 4833.806647734052, 4846.224604063333, 4854.271859221145, 5226.581873450191, 5232.607554433509, 5237.685738855681, 5249.13655652238, 5264.605154613496, 4981.2030075187795, 5142.994232249351, 5149.716765577929, 5154.8969020620125, 5162.793488260428, 4822.662413631152, 4824.903501930012, 4828.251620998751, 4845.466998630078, 4846.079244943449, 3771.849126034928, 3785.9432108517844, 3788.905277368088, 3791.4691943128537, 3795.6963442925053, 4958.0, 5225.973870130625, 5230.555402790653, 5239.26650268955, 5272.815451459334, 5060.974695126648, 5077.416097148727, 5082.2122571001555, 5115.974420127875, 5135.281060651437, 5032.672876263177, 5044.4451110377095, 5058.317127187783, 5087.956968821309, 5115.846524604268, 4844.588210002189, 5002.224655178453, 5015.297858299914, 5030.798768049237, 5048.343715316985, 4835.896385072505, 4961.900761984813, 5085.694858308513, 5085.872853178552, 5094.561171209493, 4975.154223781893, 4979.950200498093, 4982.302477653059, 4987.925181122214, 4998.300237966615], "value": 3828.198899135407, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.0821430889275, 594.5748789615424, 595.9987221467934, 691.3432239372597, 715.7673756029292, 12613.18014329068, 13433.071799837922, 13578.592642220725, 13590.456381744718, 13606.795898061524, 13033.478660853578, 13470.595882123527, 13546.39041243144, 13558.32208389579, 13618.387172577233, 767.1024900866, 790.3163763344694, 793.7698067560401, 793.9325157361632, 794.5153456391597, 641.1664835713577, 751.8270797716528, 782.7103971530513, 783.0916137280756, 789.1122487201898, 819.983600327993, 820.3027426687328, 820.4461988157999, 820.7414664380731, 821.3470291118546, 815.6411179081185, 815.8490679224333, 816.0126247240846, 816.1063635319346, 819.19309480162, 491.2336754662738, 578.5920925747338, 591.361329763854, 594.8334466349429, 598.6109029131056, 13097.607071787766, 13133.802992955114, 13140.277284749334, 13151.60545183646, 13157.0, 12350.938245308806, 12503.312317822516, 12559.120861539695, 12678.619641410773, 13214.339283035906, 10815.459227038617, 10999.945000275027, 11031.779364412747, 11282.153838462149, 11365.829512557319, 4016.176683779818, 4080.979595102035, 4114.958850411487, 4198.85304014358, 4203.453551038376, 58877.41122588762, 59526.40473595252, 59673.701631492, 59935.400645993854, 63363.7327253457, 9795.67128779682, 9903.752406189758, 9968.202543796478, 9979.401235925867, 10167.4916254187, 19163.904180479007, 19247.326343577908, 19291.42125736202, 19386.70919936202, 19399.0, 6441.5490915635455, 6610.338966103352, 6698.765543206062, 6745.9662701686175, 6766.526343156031, 3580.3555360035443, 3595.9280814383565, 3607.644984657347, 3612.548431446069, 3614.1868079682386, 2691.4078902641386, 2750.944981100367, 2762.1575419497244, 2763.958540621892, 2798.6921438641734, 463.83302011275913, 464.52850356887785, 466.2190830359135, 469.5773803576808, 473.0893030915474, 464.50530185352494, 473.7110362678763, 475.6884240822267, 479.918413869643, 495.1112752609075, 7166.140063192451, 7240.493165478471, 7395.85208295831, 7470.252974702486, 7718.498297610638, 387.9941800872989, 436.1255682356854, 511.57794819273505, 563.5153767759723, 579.325086274494, 3531.099569609733, 3761.830717617659, 3922.7842468663926, 4082.5713300103657, 4191.7694526801315, 93492.0, 94638.0, 95513.0, 99268.50365748125, 102120.0, 81095.0, 83713.58143209366, 84290.0, 84382.57808710917, 88483.55758221168, 73816.63091684507, 73893.0, 73953.0, 74064.25935740682, 74940.62529687317, 6923.342282483156, 7216.71133154689, 7218.046611774607, 7278.818029549304, 7393.778186654409, 41657.20942091698, 41735.7913210432, 43063.354049688656, 43093.784531077144, 43744.0, 58331.4166858326, 61392.69303653453, 63084.68457657682, 63406.0, 66570.33429665834, 9135.58889849945, 9270.768230794283, 9294.581743821409, 9775.217982561237, 9827.656032038843, 9958.0, 10274.33216840903, 10674.039336459757, 10705.464726763475, 11106.833397498882, 9582.0, 9672.70981870545, 9975.401475911469, 10084.0, 10091.899081009096, 9869.358491698016, 10014.849777253492, 10097.394156350643, 10133.18934485254, 10414.062734353945, 8637.740867773977, 8739.694110706096, 9351.205147562261, 9357.0, 9373.531323433937, 7644.235576442311, 7670.578118203553, 8073.233042860918, 8091.55496447701, 8105.392095592736, 7344.596047217455, 7687.961560192163, 8319.750407487785, 8452.535110568977, 8461.0], "value": 6196.121429836972, "unit": "score"}], "name": "dromaeo_css", "value": 6295.358120242366, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 115.0, 112.0, 113.0, 112.0, 113.0, 112.0, 113.0, 112.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 123.0, 124.0, 188.0, 122.0, 128.0, 148.0, 122.0, 157.0, 125.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 163.0, 161.0, 161.0, 162.0, 162.0, 162.0, 162.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 129.0, 76.0, 77.0, 75.0, 76.0, 106.0, 76.0], "value": 84.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 114.0, 85.0, 86.0, 86.0, 116.0, 85.0, 86.0, 135.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 117.0, 117.0, 119.0, 117.0, 117.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 62.0, 61.0, 61.0, 60.0, 61.0, 61.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 66.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 85.0, 81.0, 79.0, 79.0, 80.0, 80.0, 83.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 133.0, 133.0, 139.0, 135.0, 137.0, 133.0, 130.0, 174.0, 152.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 163.0, 165.0, 168.0, 164.0, 164.0, 161.0, 167.0, 163.0, 165.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 67.0, 67.0, 65.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1530.4, "alertThreshold": 2.0}]} 09:35:32 INFO - Return code: 0 09:35:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:35:32 INFO - # TBPL SUCCESS # 09:35:32 INFO - Running post-action listener: _resource_record_post_action 09:35:32 INFO - [mozharness: 2016-07-21 16:35:32.319589Z] Finished run-tests step (success) 09:35:32 INFO - Running post-run listener: _resource_record_post_run 09:35:32 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 8192; Write bytes: 549208064; Read time: 352; Write time: 517964 09:35:32 INFO - TinderboxPrint: CPU usage
13.0% 09:35:32 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 352 09:35:32 INFO - TinderboxPrint: I/O write bytes / time
549,208,064 / 517,964 09:35:32 INFO - TinderboxPrint: CPU idle
8,334.4 (87.0%) 09:35:32 INFO - TinderboxPrint: CPU user
1,199.2 (12.5%) 09:35:32 INFO - TinderboxPrint: Swap in / out
0 / 0 09:35:32 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 259928064; Read time: 152; Write time: 266168 09:35:32 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289280000; Read time: 200; Write time: 251796 09:35:32 INFO - Running post-run listener: _upload_blobber_files 09:35:32 INFO - Blob upload gear active. 09:35:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:35:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:35:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:35:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:35:33 INFO - (blobuploader) - INFO - Open directory for files ... 09:35:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:35:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:35:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:48 INFO - (blobuploader) - INFO - Done attempting. 09:35:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:35:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:35:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:49 INFO - (blobuploader) - INFO - Done attempting. 09:35:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:35:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:35:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:35:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:35:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:35:50 INFO - (blobuploader) - INFO - Done attempting. 09:35:50 INFO - (blobuploader) - INFO - Iteration through files over. 09:35:50 INFO - Return code: 0 09:35:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:35:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:35:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a836b017e1ffa2c98ada7219808afc0a9d6964ee11a2a5a5609cd43926fb3b35cef4ea852c6be4cc513d9173307e78419853ab11bf016d9f0e8598869c99bdc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6debe8d88bfb341d31fdba5df9e2c336c38952eaa6b87c915965b67f15b63d5d0599ebdf94452dc38ce7d8e456cdcd5a749ebd616ef05b59dfc899f79fdb329b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cdb40277cfb377c86cdb2d2a7696e036c8e032522e6fb6dd8bd97a07bffb0905bd254f5778b5a7b0c5b1343d7c5e853dbfad14b2ea5a0ffde84d83b49e1d706a"} 09:35:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:35:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:35:50 INFO - Contents: 09:35:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a836b017e1ffa2c98ada7219808afc0a9d6964ee11a2a5a5609cd43926fb3b35cef4ea852c6be4cc513d9173307e78419853ab11bf016d9f0e8598869c99bdc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6debe8d88bfb341d31fdba5df9e2c336c38952eaa6b87c915965b67f15b63d5d0599ebdf94452dc38ce7d8e456cdcd5a749ebd616ef05b59dfc899f79fdb329b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cdb40277cfb377c86cdb2d2a7696e036c8e032522e6fb6dd8bd97a07bffb0905bd254f5778b5a7b0c5b1343d7c5e853dbfad14b2ea5a0ffde84d83b49e1d706a"} 09:35:50 INFO - Running post-run listener: copy_logs_to_upload_dir 09:35:50 INFO - Copying logs to upload dir... 09:35:50 INFO - mkdir: /builds/slave/test/build/upload/logs 09:35:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.551231 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-21 09:35:50.348456) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 09:35:50.351840) ========= 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=20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a836b017e1ffa2c98ada7219808afc0a9d6964ee11a2a5a5609cd43926fb3b35cef4ea852c6be4cc513d9173307e78419853ab11bf016d9f0e8598869c99bdc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6debe8d88bfb341d31fdba5df9e2c336c38952eaa6b87c915965b67f15b63d5d0599ebdf94452dc38ce7d8e456cdcd5a749ebd616ef05b59dfc899f79fdb329b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cdb40277cfb377c86cdb2d2a7696e036c8e032522e6fb6dd8bd97a07bffb0905bd254f5778b5a7b0c5b1343d7c5e853dbfad14b2ea5a0ffde84d83b49e1d706a"} build_url:https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/ITLHBwybQiamhf_CzwYZ4g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/a836b017e1ffa2c98ada7219808afc0a9d6964ee11a2a5a5609cd43926fb3b35cef4ea852c6be4cc513d9173307e78419853ab11bf016d9f0e8598869c99bdc2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6debe8d88bfb341d31fdba5df9e2c336c38952eaa6b87c915965b67f15b63d5d0599ebdf94452dc38ce7d8e456cdcd5a749ebd616ef05b59dfc899f79fdb329b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/cdb40277cfb377c86cdb2d2a7696e036c8e032522e6fb6dd8bd97a07bffb0905bd254f5778b5a7b0c5b1343d7c5e853dbfad14b2ea5a0ffde84d83b49e1d706a"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 09:35:50.377787) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:35:50.378201) ========= 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=20e666a3d4cfbb71505a330e000001b4-1469116332.507359-694542259 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 09:35:50.462328) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 09:35:50.462656) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 09:35:50.462949) ========= ========= Total master_lag: 0.18 =========