builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1470612564.41 results: success (0) buildid: 20160807150152 builduid: fa817272ff634ddb9b55e52d06355727 revision: ad2be3aadc626da670b5d0dd60e19afe2108b25b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.412842) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.413288) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.413565) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.454558) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.455183) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-07 16:29:25-- 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-08-07 16:29:25 (46.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.133670 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.620470) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.620852) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016359 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:24.678802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-07 16:29:24.679107) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ad2be3aadc626da670b5d0dd60e19afe2108b25b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ad2be3aadc626da670b5d0dd60e19afe2108b25b --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=9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-07 16:29:25,684 truncating revision to first 12 chars 2016-08-07 16:29:25,684 Setting DEBUG logging. 2016-08-07 16:29:25,684 attempt 1/10 2016-08-07 16:29:25,684 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ad2be3aadc62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-07 16:29:26,909 unpacking tar archive at: mozilla-central-ad2be3aadc62/testing/mozharness/ program finished with exit code 0 elapsedTime=1.554999 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-07 16:29:26.249733) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:26.250044) ========= script_repo_revision: ad2be3aadc626da670b5d0dd60e19afe2108b25b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:26.250478) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:26.250749) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-07 16:29:26.259790) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-07 16:29:26.260149) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:29:27 INFO - MultiFileLogger online at 20160807 16:29:27 in /builds/slave/test 16:29:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:29:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:29:27 INFO - {'append_to_log': False, 16:29:27 INFO - 'base_work_dir': '/builds/slave/test', 16:29:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:29:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:29:27 INFO - 'branch': 'Firefox-Non-PGO', 16:29:27 INFO - 'buildbot_json_path': 'buildprops.json', 16:29:27 INFO - 'config_files': ('talos/linux_config.py',), 16:29:27 INFO - 'default_actions': ('clobber', 16:29:27 INFO - 'read-buildbot-config', 16:29:27 INFO - 'download-and-extract', 16:29:27 INFO - 'populate-webroot', 16:29:27 INFO - 'create-virtualenv', 16:29:27 INFO - 'install', 16:29:27 INFO - 'run-tests'), 16:29:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:29:27 INFO - 'download_minidump_stackwalk': True, 16:29:27 INFO - 'download_symbols': 'ondemand', 16:29:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:29:27 INFO - 'tooltool.py': '/tools/tooltool.py', 16:29:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:29:27 INFO - '/tools/misc-python/virtualenv.py')}, 16:29:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:29:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:29:27 INFO - 'installer_path': 'installer.exe', 16:29:27 INFO - 'log_level': 'info', 16:29:27 INFO - 'log_name': 'talos', 16:29:27 INFO - 'log_to_console': True, 16:29:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:29:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:29:27 INFO - 'opt_config_files': (), 16:29:27 INFO - 'pip_index': False, 16:29:27 INFO - 'sps_profile': False, 16:29:27 INFO - 'sps_profile_interval': 0, 16:29:27 INFO - 'suite': 'dromaeojs', 16:29:27 INFO - 'system_bits': '32', 16:29:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:29:27 INFO - 'title': 'talos-linux64-ix-040', 16:29:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:29:27 INFO - 'use_talos_json': True, 16:29:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:29:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:29:27 INFO - 'work_dir': 'build'} 16:29:27 INFO - [mozharness: 2016-08-07 23:29:27.355386Z] Running clobber step. 16:29:27 INFO - Running pre-action listener: _resource_record_pre_action 16:29:27 INFO - Running main action method: clobber 16:29:27 INFO - rmtree: /builds/slave/test/build 16:29:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:29:28 INFO - Running post-action listener: _resource_record_post_action 16:29:28 INFO - [mozharness: 2016-08-07 23:29:28.339579Z] Finished clobber step (success) 16:29:28 INFO - [mozharness: 2016-08-07 23:29:28.339680Z] Running read-buildbot-config step. 16:29:28 INFO - Running pre-action listener: _resource_record_pre_action 16:29:28 INFO - Running main action method: read_buildbot_config 16:29:28 INFO - Using buildbot properties: 16:29:28 INFO - { 16:29:28 INFO - "project": "", 16:29:28 INFO - "product": "firefox", 16:29:28 INFO - "script_repo_revision": "production", 16:29:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:29:28 INFO - "repository": "", 16:29:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:29:28 INFO - "buildid": "20160807150152", 16:29:28 INFO - "pgo_build": "False", 16:29:28 INFO - "basedir": "/builds/slave/test", 16:29:28 INFO - "buildnumber": 176, 16:29:28 INFO - "slavename": "talos-linux64-ix-040", 16:29:28 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:29:28 INFO - "platform": "ubuntu64_hw", 16:29:28 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:29:28 INFO - "branch": "mozilla-central", 16:29:28 INFO - "repo_path": "mozilla-central", 16:29:28 INFO - "revision": "ad2be3aadc626da670b5d0dd60e19afe2108b25b", 16:29:28 INFO - "stage_platform": "linux64", 16:29:28 INFO - "builduid": "fa817272ff634ddb9b55e52d06355727", 16:29:28 INFO - "slavebuilddir": "test" 16:29:28 INFO - } 16:29:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 16:29:28 INFO - Running post-action listener: _resource_record_post_action 16:29:28 INFO - [mozharness: 2016-08-07 23:29:28.342168Z] Finished read-buildbot-config step (success) 16:29:28 INFO - [mozharness: 2016-08-07 23:29:28.342256Z] Running download-and-extract step. 16:29:28 INFO - Running pre-action listener: _resource_record_pre_action 16:29:28 INFO - Running main action method: download_and_extract 16:29:28 INFO - mkdir: /builds/slave/test/build/tests 16:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:28 INFO - trying https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:29:28 INFO - Downloading https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:29:28 INFO - Downloaded 1361 bytes. 16:29:28 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 16:29:28 INFO - Using the following test package requirements: 16:29:28 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:29:28 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:29:28 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:29:28 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'jsshell-linux-x86_64.zip'], 16:29:28 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:29:28 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 16:29:28 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:29:28 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:29:28 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:29:28 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 16:29:28 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:29:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:29:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:28 INFO - trying https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:29:28 INFO - Downloading https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:29:30 INFO - Downloaded 20257838 bytes. 16:29:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:29:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:29:31 INFO - Return code: 0 16:29:31 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:29: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:31 INFO - trying https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:29:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 16:29:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:29:32 INFO - Downloaded 20257838 bytes. 16:29:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:29:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:29:33 INFO - Return code: 0 16:29:33 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:33 INFO - trying https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:29:33 INFO - Downloading https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 16:29:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:29:35 INFO - Downloaded 14413723 bytes. 16:29:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:29:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:29:35 INFO - Return code: 0 16:29:35 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:35 INFO - trying https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:29:35 INFO - Downloading https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:29:37 INFO - Downloaded 56128373 bytes. 16:29:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:29:37 INFO - mkdir: /builds/slave/test/properties 16:29:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:29:37 INFO - Writing to file /builds/slave/test/properties/build_url 16:29:37 INFO - Contents: 16:29:37 INFO - build_url:https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 16:29:37 INFO - Running post-action listener: _resource_record_post_action 16:29:37 INFO - Running post-action listener: set_extra_try_arguments 16:29:37 INFO - [mozharness: 2016-08-07 23:29:37.708551Z] Finished download-and-extract step (success) 16:29:37 INFO - [mozharness: 2016-08-07 23:29:37.708785Z] Running populate-webroot step. 16:29:37 INFO - Running pre-action listener: _resource_record_pre_action 16:29:37 INFO - Running main action method: populate_webroot 16:29:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:29:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:29:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:29:37 INFO - '--tppagecycles', 16:29:37 INFO - '7'], 16:29:37 INFO - 'tests': ['tsvgm']}}, 16:29:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:29:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:29:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:29:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:29:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:29:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['damp', 'tps']}, 16:29:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'tests': ['damp', 'tps']}, 16:29:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['dromaeo_dom']}, 16:29:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:29:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['basic_compositor_video']}, 16:29:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:29:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['a11yr', 16:29:37 INFO - 'ts_paint', 16:29:37 INFO - 'tpaint', 16:29:37 INFO - 'sessionrestore', 16:29:37 INFO - 'sessionrestore_no_auto_restore', 16:29:37 INFO - 'tabpaint']}, 16:29:37 INFO - 'other-e10s': {'tests': ['a11yr', 16:29:37 INFO - 'ts_paint', 16:29:37 INFO - 'tpaint', 16:29:37 INFO - 'sessionrestore', 16:29:37 INFO - 'sessionrestore_no_auto_restore', 16:29:37 INFO - 'tabpaint']}, 16:29:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:29:37 INFO - 'ts_paint', 16:29:37 INFO - 'tpaint', 16:29:37 INFO - 'sessionrestore', 16:29:37 INFO - 'sessionrestore_no_auto_restore', 16:29:37 INFO - 'tabpaint']}, 16:29:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:29:37 INFO - 'ts_paint', 16:29:37 INFO - 'tpaint', 16:29:37 INFO - 'sessionrestore', 16:29:37 INFO - 'sessionrestore_no_auto_restore', 16:29:37 INFO - 'tabpaint']}, 16:29:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['a11yr', 16:29:37 INFO - 'ts_paint', 16:29:37 INFO - 'tpaint', 16:29:37 INFO - 'sessionrestore', 16:29:37 INFO - 'sessionrestore_no_auto_restore', 16:29:37 INFO - 'tabpaint']}, 16:29:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['a11yr', 16:29:37 INFO - 'ts_paint', 16:29:37 INFO - 'tpaint', 16:29:37 INFO - 'sessionrestore', 16:29:37 INFO - 'sessionrestore_no_auto_restore', 16:29:37 INFO - 'tabpaint']}, 16:29:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['tsvgx', 16:29:37 INFO - 'tsvgr_opacity', 16:29:37 INFO - 'tart', 16:29:37 INFO - 'tscrollx', 16:29:37 INFO - 'cart']}, 16:29:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:29:37 INFO - 'tsvgr_opacity', 16:29:37 INFO - 'tart', 16:29:37 INFO - 'tscrollx', 16:29:37 INFO - 'cart']}, 16:29:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'talos_options': ['--disable-e10s'], 16:29:37 INFO - 'tests': ['tp5o']}, 16:29:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'tests': ['tp5o']}, 16:29:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'talos_options': ['--disable-e10s', 16:29:37 INFO - '--xperf_path', 16:29:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:37 INFO - 'tests': ['tp5n']}, 16:29:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:29:37 INFO - 'talos_options': ['--xperf_path', 16:29:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:29:37 INFO - 'tests': ['tp5n']}}, 16:29:37 INFO - 'talos.zip': {'path': '', 16:29:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:29:37 INFO - Running post-action listener: _resource_record_post_action 16:29:37 INFO - [mozharness: 2016-08-07 23:29:37.729924Z] Finished populate-webroot step (success) 16:29:37 INFO - [mozharness: 2016-08-07 23:29:37.730013Z] Running create-virtualenv step. 16:29:37 INFO - Running pre-action listener: _resource_record_pre_action 16:29:37 INFO - Running main action method: create_virtualenv 16:29:37 INFO - Creating virtualenv /builds/slave/test/build/venv 16:29:37 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 16:29:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:29:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:29:37 INFO - Using real prefix '/usr' 16:29:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:29:38 INFO - Installing distribute.............................................................................................................................................................................................done. 16:29:40 INFO - Installing pip.................done. 16:29:40 INFO - Return code: 0 16:29:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:29:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:40 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 16:29:40 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 16:29:40 INFO - Using env: {'DISPLAY': ':0', 16:29:40 INFO - 'HOME': '/home/cltbld', 16:29:40 INFO - 'LANG': 'en_US.UTF-8', 16:29:40 INFO - 'LANGUAGE': 'en_US:en', 16:29:40 INFO - 'LOGNAME': 'cltbld', 16:29:40 INFO - 'MAIL': '/var/mail/cltbld', 16:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:40 INFO - 'MOZ_NO_REMOTE': '1', 16:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:40 INFO - 'NO_EM_RESTART': '1', 16:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:40 INFO - 'PWD': '/builds/slave/test', 16:29:40 INFO - 'SHELL': '/bin/bash', 16:29:40 INFO - 'SHLVL': '1', 16:29:40 INFO - 'TERM': 'linux', 16:29:40 INFO - 'TMOUT': '86400', 16:29:40 INFO - 'USER': 'cltbld', 16:29:40 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:40 INFO - '_': '/tools/buildbot/bin/python'} 16:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:40 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 16:29:40 INFO - Cleaning up... 16:29:40 INFO - Return code: 0 16:29:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:29:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:40 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:40 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 16:29:40 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 16:29:40 INFO - Using env: {'DISPLAY': ':0', 16:29:40 INFO - 'HOME': '/home/cltbld', 16:29:40 INFO - 'LANG': 'en_US.UTF-8', 16:29:40 INFO - 'LANGUAGE': 'en_US:en', 16:29:40 INFO - 'LOGNAME': 'cltbld', 16:29:40 INFO - 'MAIL': '/var/mail/cltbld', 16:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:40 INFO - 'MOZ_NO_REMOTE': '1', 16:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:40 INFO - 'NO_EM_RESTART': '1', 16:29:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:40 INFO - 'PWD': '/builds/slave/test', 16:29:40 INFO - 'SHELL': '/bin/bash', 16:29:40 INFO - 'SHLVL': '1', 16:29:40 INFO - 'TERM': 'linux', 16:29:40 INFO - 'TMOUT': '86400', 16:29:40 INFO - 'USER': 'cltbld', 16:29:40 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:40 INFO - '_': '/tools/buildbot/bin/python'} 16:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:40 INFO - Downloading/unpacking psutil>=3.1.1 16:29:40 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 16:29:40 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 16:29:40 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 16:29:40 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 16:29:40 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 16:29:40 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 16:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:29:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:42 INFO - Installing collected packages: psutil 16:29:42 INFO - Running setup.py install for psutil 16:29:42 INFO - building 'psutil._psutil_linux' extension 16:29:42 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 16:29:42 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 16:29:43 INFO - building 'psutil._psutil_posix' extension 16:29:43 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 16:29:43 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 16:29:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:29:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:29:43 INFO - Successfully installed psutil 16:29:43 INFO - Cleaning up... 16:29:43 INFO - Return code: 0 16:29:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:29:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:43 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:43 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 16:29:43 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 16:29:43 INFO - Using env: {'DISPLAY': ':0', 16:29:43 INFO - 'HOME': '/home/cltbld', 16:29:43 INFO - 'LANG': 'en_US.UTF-8', 16:29:43 INFO - 'LANGUAGE': 'en_US:en', 16:29:43 INFO - 'LOGNAME': 'cltbld', 16:29:43 INFO - 'MAIL': '/var/mail/cltbld', 16:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:43 INFO - 'MOZ_NO_REMOTE': '1', 16:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:43 INFO - 'NO_EM_RESTART': '1', 16:29:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:43 INFO - 'PWD': '/builds/slave/test', 16:29:43 INFO - 'SHELL': '/bin/bash', 16:29:43 INFO - 'SHLVL': '1', 16:29:43 INFO - 'TERM': 'linux', 16:29:43 INFO - 'TMOUT': '86400', 16:29:43 INFO - 'USER': 'cltbld', 16:29:43 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:43 INFO - '_': '/tools/buildbot/bin/python'} 16:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:29:43 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 16:29:43 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 16:29:43 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 16:29:43 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 16:29:43 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 16:29:43 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 16:29:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:29:45 INFO - Installing collected packages: mozsystemmonitor 16:29:45 INFO - Running setup.py install for mozsystemmonitor 16:29:45 INFO - Successfully installed mozsystemmonitor 16:29:45 INFO - Cleaning up... 16:29:45 INFO - Return code: 0 16:29:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:45 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:45 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 16:29:45 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 16:29:45 INFO - Using env: {'DISPLAY': ':0', 16:29:45 INFO - 'HOME': '/home/cltbld', 16:29:45 INFO - 'LANG': 'en_US.UTF-8', 16:29:45 INFO - 'LANGUAGE': 'en_US:en', 16:29:45 INFO - 'LOGNAME': 'cltbld', 16:29:45 INFO - 'MAIL': '/var/mail/cltbld', 16:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:45 INFO - 'MOZ_NO_REMOTE': '1', 16:29:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:45 INFO - 'NO_EM_RESTART': '1', 16:29:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:45 INFO - 'PWD': '/builds/slave/test', 16:29:45 INFO - 'SHELL': '/bin/bash', 16:29:45 INFO - 'SHLVL': '1', 16:29:45 INFO - 'TERM': 'linux', 16:29:45 INFO - 'TMOUT': '86400', 16:29:45 INFO - 'USER': 'cltbld', 16:29:45 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:45 INFO - '_': '/tools/buildbot/bin/python'} 16:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:45 INFO - Downloading/unpacking jsonschema==2.5.1 16:29:45 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 16:29:45 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 16:29:45 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 16:29:45 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 16:29:45 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 16:29:45 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 16:29:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:29:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:29:47 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 16:29:47 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 16:29:47 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 16:29:47 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 16:29:47 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 16:29:47 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 16:29:47 INFO - Downloading functools32-3.2.3-2.tar.gz 16:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:29:47 INFO - warning: no files found matching '*.txt' 16:29:47 INFO - no previously-included directories found matching 'build' 16:29:47 INFO - no previously-included directories found matching 'dist' 16:29:47 INFO - no previously-included directories found matching '.git*' 16:29:47 INFO - Installing collected packages: jsonschema, functools32 16:29:47 INFO - Running setup.py install for functools32 16:29:48 INFO - warning: no files found matching '*.txt' 16:29:48 INFO - no previously-included directories found matching 'build' 16:29:48 INFO - no previously-included directories found matching 'dist' 16:29:48 INFO - no previously-included directories found matching '.git*' 16:29:48 INFO - Successfully installed jsonschema functools32 16:29:48 INFO - Cleaning up... 16:29:48 INFO - Return code: 0 16:29:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:29:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:48 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:48 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:29:48 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 functools32==3.2.3-2 16:29:48 INFO - Using env: {'DISPLAY': ':0', 16:29:48 INFO - 'HOME': '/home/cltbld', 16:29:48 INFO - 'LANG': 'en_US.UTF-8', 16:29:48 INFO - 'LANGUAGE': 'en_US:en', 16:29:48 INFO - 'LOGNAME': 'cltbld', 16:29:48 INFO - 'MAIL': '/var/mail/cltbld', 16:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:48 INFO - 'MOZ_NO_REMOTE': '1', 16:29:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:48 INFO - 'NO_EM_RESTART': '1', 16:29:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:48 INFO - 'PWD': '/builds/slave/test', 16:29:48 INFO - 'SHELL': '/bin/bash', 16:29:48 INFO - 'SHLVL': '1', 16:29:48 INFO - 'TERM': 'linux', 16:29:48 INFO - 'TMOUT': '86400', 16:29:48 INFO - 'USER': 'cltbld', 16:29:48 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:48 INFO - '_': '/tools/buildbot/bin/python'} 16:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:29:48 INFO - Cleaning up... 16:29:48 INFO - Return code: 0 16:29:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:29:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:48 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:48 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 16:29:48 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 16:29:48 INFO - Using env: {'DISPLAY': ':0', 16:29:48 INFO - 'HOME': '/home/cltbld', 16:29:48 INFO - 'LANG': 'en_US.UTF-8', 16:29:48 INFO - 'LANGUAGE': 'en_US:en', 16:29:48 INFO - 'LOGNAME': 'cltbld', 16:29:48 INFO - 'MAIL': '/var/mail/cltbld', 16:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:48 INFO - 'MOZ_NO_REMOTE': '1', 16:29:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:48 INFO - 'NO_EM_RESTART': '1', 16:29:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:48 INFO - 'PWD': '/builds/slave/test', 16:29:48 INFO - 'SHELL': '/bin/bash', 16:29:48 INFO - 'SHLVL': '1', 16:29:48 INFO - 'TERM': 'linux', 16:29:48 INFO - 'TMOUT': '86400', 16:29:48 INFO - 'USER': 'cltbld', 16:29:48 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:48 INFO - '_': '/tools/buildbot/bin/python'} 16:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:48 INFO - Downloading/unpacking blobuploader==1.2.4 16:29:48 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 16:29:48 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 16:29:48 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 16:29:48 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 16:29:48 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 16:29:48 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 16:29:50 INFO - Downloading blobuploader-1.2.4.tar.gz 16:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:29:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:29:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 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 16:29:50 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 16:29:51 INFO - Downloading docopt-0.6.1.tar.gz 16:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:29:51 INFO - Installing collected packages: blobuploader, requests, docopt 16:29:51 INFO - Running setup.py install for blobuploader 16:29:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:29:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:29:51 INFO - Running setup.py install for requests 16:29:51 INFO - Running setup.py install for docopt 16:29:51 INFO - Successfully installed blobuploader requests docopt 16:29:51 INFO - Cleaning up... 16:29:51 INFO - Return code: 0 16:29:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:51 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:51 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:51 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 16:29:51 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 16:29:51 INFO - Using env: {'DISPLAY': ':0', 16:29:51 INFO - 'HOME': '/home/cltbld', 16:29:51 INFO - 'LANG': 'en_US.UTF-8', 16:29:51 INFO - 'LANGUAGE': 'en_US:en', 16:29:51 INFO - 'LOGNAME': 'cltbld', 16:29:51 INFO - 'MAIL': '/var/mail/cltbld', 16:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:51 INFO - 'MOZ_NO_REMOTE': '1', 16:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:51 INFO - 'NO_EM_RESTART': '1', 16:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:51 INFO - 'PWD': '/builds/slave/test', 16:29:51 INFO - 'SHELL': '/bin/bash', 16:29:51 INFO - 'SHLVL': '1', 16:29:51 INFO - 'TERM': 'linux', 16:29:51 INFO - 'TMOUT': '86400', 16:29:51 INFO - 'USER': 'cltbld', 16:29:51 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:51 INFO - '_': '/tools/buildbot/bin/python'} 16:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:52 INFO - Running setup.py (path:/tmp/pip-WrlAE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:52 INFO - Running setup.py (path:/tmp/pip-INyn5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:52 INFO - Running setup.py (path:/tmp/pip-7X1497-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:52 INFO - Running setup.py (path:/tmp/pip-pya1ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:52 INFO - Running setup.py (path:/tmp/pip-yh_nwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:52 INFO - Running setup.py (path:/tmp/pip-OqCnxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:52 INFO - Running setup.py (path:/tmp/pip-Or86Hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:52 INFO - Running setup.py (path:/tmp/pip-fX6aWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:52 INFO - Running setup.py (path:/tmp/pip-TrSRsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:52 INFO - Running setup.py (path:/tmp/pip-CklcSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:52 INFO - Running setup.py (path:/tmp/pip-ufKLC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:52 INFO - Running setup.py (path:/tmp/pip-2nhluJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:52 INFO - Running setup.py (path:/tmp/pip-jfLkR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:52 INFO - Running setup.py (path:/tmp/pip-wokCAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:53 INFO - Running setup.py (path:/tmp/pip-FJh33e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:53 INFO - Running setup.py (path:/tmp/pip-V_7iOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:53 INFO - Running setup.py (path:/tmp/pip-_5a1g3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:29:53 INFO - Running setup.py install for manifestparser 16:29:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:29:53 INFO - Running setup.py install for mozcrash 16:29:53 INFO - Running setup.py install for mozdebug 16:29:53 INFO - Running setup.py install for mozdevice 16:29:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:29:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:29:53 INFO - Running setup.py install for mozfile 16:29:53 INFO - Running setup.py install for mozhttpd 16:29:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:29:53 INFO - Running setup.py install for mozinfo 16:29:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:29:53 INFO - Running setup.py install for mozInstall 16:29:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Running setup.py install for mozleak 16:29:54 INFO - Running setup.py install for mozlog 16:29:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Running setup.py install for moznetwork 16:29:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Running setup.py install for mozprocess 16:29:54 INFO - Running setup.py install for mozprofile 16:29:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Running setup.py install for mozrunner 16:29:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:29:54 INFO - Running setup.py install for mozscreenshot 16:29:54 INFO - Running setup.py install for moztest 16:29:54 INFO - Running setup.py install for mozversion 16:29:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:29:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:29:55 INFO - Cleaning up... 16:29:55 INFO - Return code: 0 16:29:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:55 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:55 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:55 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 16:29:55 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 16:29:55 INFO - Using env: {'DISPLAY': ':0', 16:29:55 INFO - 'HOME': '/home/cltbld', 16:29:55 INFO - 'LANG': 'en_US.UTF-8', 16:29:55 INFO - 'LANGUAGE': 'en_US:en', 16:29:55 INFO - 'LOGNAME': 'cltbld', 16:29:55 INFO - 'MAIL': '/var/mail/cltbld', 16:29:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:55 INFO - 'MOZ_NO_REMOTE': '1', 16:29:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:55 INFO - 'NO_EM_RESTART': '1', 16:29:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:55 INFO - 'PWD': '/builds/slave/test', 16:29:55 INFO - 'SHELL': '/bin/bash', 16:29:55 INFO - 'SHLVL': '1', 16:29:55 INFO - 'TERM': 'linux', 16:29:55 INFO - 'TMOUT': '86400', 16:29:55 INFO - 'USER': 'cltbld', 16:29:55 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:55 INFO - '_': '/tools/buildbot/bin/python'} 16:29:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:29:55 INFO - Running setup.py (path:/tmp/pip-T3s8IF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:29:55 INFO - Running setup.py (path:/tmp/pip-aHUfv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:29:55 INFO - Running setup.py (path:/tmp/pip-DyDnaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:29:55 INFO - Running setup.py (path:/tmp/pip-Y6UmC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:29:55 INFO - Running setup.py (path:/tmp/pip-xQseAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:29:55 INFO - Running setup.py (path:/tmp/pip-3dVeqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:29:55 INFO - Running setup.py (path:/tmp/pip-dX1FkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:29:55 INFO - Running setup.py (path:/tmp/pip-MYQe2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:29:55 INFO - Running setup.py (path:/tmp/pip-kRq52a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:29:55 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)) 16:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:29:55 INFO - Running setup.py (path:/tmp/pip-FD4QvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:29:56 INFO - Running setup.py (path:/tmp/pip-TdgNWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:29:56 INFO - Running setup.py (path:/tmp/pip-ubfCth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:29:56 INFO - Running setup.py (path:/tmp/pip-S3ZdqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:29:56 INFO - Running setup.py (path:/tmp/pip-iNxZal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:56 INFO - Running setup.py (path:/tmp/pip-u39qDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:29:56 INFO - Running setup.py (path:/tmp/pip-SgFa9C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:29:56 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)) 16:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:29:56 INFO - Running setup.py (path:/tmp/pip-7MD_gS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:29:56 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)) 16:29:56 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)) 16:29:56 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)) 16:29:56 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)) 16:29:56 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)) 16:29:56 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)) 16:29:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:29:56 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 16:29:56 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 16:29:56 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 16:29:56 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 16:29:56 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 16:29:56 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 16:29:58 INFO - Downloading blessings-1.6.tar.gz 16:29:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:29:58 INFO - Installing collected packages: blessings 16:29:58 INFO - Running setup.py install for blessings 16:29:58 INFO - Successfully installed blessings 16:29:58 INFO - Cleaning up... 16:29:58 INFO - Return code: 0 16:29:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:29:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:58 INFO - Reading from file tmpfile_stdout 16:29:58 INFO - Current package versions: 16:29:58 INFO - argparse == 1.2.1 16:29:58 INFO - blessings == 1.6 16:29:58 INFO - blobuploader == 1.2.4 16:29:58 INFO - docopt == 0.6.1 16:29:58 INFO - functools32 == 3.2.3-2 16:29:58 INFO - jsonschema == 2.5.1 16:29:58 INFO - manifestparser == 1.1 16:29:58 INFO - mozInstall == 1.12 16:29:58 INFO - mozcrash == 0.17 16:29:58 INFO - mozdebug == 0.1 16:29:58 INFO - mozdevice == 0.48 16:29:58 INFO - mozfile == 1.2 16:29:58 INFO - mozhttpd == 0.7 16:29:58 INFO - mozinfo == 0.9 16:29:58 INFO - mozleak == 0.1 16:29:58 INFO - mozlog == 3.3 16:29:58 INFO - moznetwork == 0.27 16:29:58 INFO - mozprocess == 0.23 16:29:58 INFO - mozprofile == 0.28 16:29:58 INFO - mozrunner == 6.12 16:29:58 INFO - mozscreenshot == 0.1 16:29:58 INFO - mozsystemmonitor == 0.3 16:29:58 INFO - moztest == 0.7 16:29:58 INFO - mozversion == 1.4 16:29:58 INFO - psutil == 3.1.1 16:29:58 INFO - requests == 1.2.3 16:29:58 INFO - wsgiref == 0.1.2 16:29:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:29:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:58 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:58 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:58 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 16:29:58 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 16:29:58 INFO - Using env: {'DISPLAY': ':0', 16:29:58 INFO - 'HOME': '/home/cltbld', 16:29:58 INFO - 'LANG': 'en_US.UTF-8', 16:29:58 INFO - 'LANGUAGE': 'en_US:en', 16:29:58 INFO - 'LOGNAME': 'cltbld', 16:29:58 INFO - 'MAIL': '/var/mail/cltbld', 16:29:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:58 INFO - 'MOZ_NO_REMOTE': '1', 16:29:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:58 INFO - 'NO_EM_RESTART': '1', 16:29:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:58 INFO - 'PWD': '/builds/slave/test', 16:29:58 INFO - 'SHELL': '/bin/bash', 16:29:58 INFO - 'SHLVL': '1', 16:29:58 INFO - 'TERM': 'linux', 16:29:58 INFO - 'TMOUT': '86400', 16:29:58 INFO - 'USER': 'cltbld', 16:29:58 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:58 INFO - '_': '/tools/buildbot/bin/python'} 16:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 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)) 16:29:59 INFO - Cleaning up... 16:29:59 INFO - Return code: 0 16:29:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:29:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:29:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:59 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:29:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:29:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:29:59 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 0x2707030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', '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 16:29:59 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 16:29:59 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 16:29:59 INFO - Using env: {'DISPLAY': ':0', 16:29:59 INFO - 'HOME': '/home/cltbld', 16:29:59 INFO - 'LANG': 'en_US.UTF-8', 16:29:59 INFO - 'LANGUAGE': 'en_US:en', 16:29:59 INFO - 'LOGNAME': 'cltbld', 16:29:59 INFO - 'MAIL': '/var/mail/cltbld', 16:29:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:29:59 INFO - 'MOZ_NO_REMOTE': '1', 16:29:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:29:59 INFO - 'NO_EM_RESTART': '1', 16:29:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:29:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:29:59 INFO - 'PWD': '/builds/slave/test', 16:29:59 INFO - 'SHELL': '/bin/bash', 16:29:59 INFO - 'SHLVL': '1', 16:29:59 INFO - 'TERM': 'linux', 16:29:59 INFO - 'TMOUT': '86400', 16:29:59 INFO - 'USER': 'cltbld', 16:29:59 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:29:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:29:59 INFO - '_': '/tools/buildbot/bin/python'} 16:29:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:29:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:29:59 INFO - Cleaning up... 16:29:59 INFO - Return code: 0 16:29:59 INFO - Running post-action listener: _resource_record_post_action 16:29:59 INFO - Running post-action listener: _start_resource_monitoring 16:29:59 INFO - Starting resource monitoring. 16:29:59 INFO - [mozharness: 2016-08-07 23:29:59.311758Z] Finished create-virtualenv step (success) 16:29:59 INFO - [mozharness: 2016-08-07 23:29:59.312242Z] Running install step. 16:29:59 INFO - Running pre-action listener: _resource_record_pre_action 16:29:59 INFO - Running main action method: install 16:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:29:59 INFO - Reading from file tmpfile_stdout 16:29:59 INFO - Detecting whether we're running mozinstall >=1.0... 16:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:29:59 INFO - Reading from file tmpfile_stdout 16:29:59 INFO - Output received: 16:29:59 INFO - Usage: mozinstall [options] installer 16:29:59 INFO - Options: 16:29:59 INFO - -h, --help show this help message and exit 16:29:59 INFO - -d DEST, --destination=DEST 16:29:59 INFO - Directory to install application into. [default: 16:29:59 INFO - "/builds/slave/test"] 16:29:59 INFO - --app=APP Application being installed. [default: firefox] 16:29:59 INFO - mkdir: /builds/slave/test/build/application 16:29:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:29:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:30:15 INFO - Reading from file tmpfile_stdout 16:30:15 INFO - Output received: 16:30:15 INFO - /builds/slave/test/build/application/firefox/firefox 16:30:15 INFO - Running post-action listener: _resource_record_post_action 16:30:15 INFO - [mozharness: 2016-08-07 23:30:15.820486Z] Finished install step (success) 16:30:15 INFO - [mozharness: 2016-08-07 23:30:15.820822Z] Running run-tests step. 16:30:15 INFO - Running pre-action listener: _resource_record_pre_action 16:30:15 INFO - Running main action method: run_tests 16:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:30:15 INFO - Python 2.7.3 16:30:15 INFO - Return code: 0 16:30:15 INFO - grabbing minidump binary from tooltool 16:30:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:30:15 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 0x29c63b0>, '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 0x29c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29c7740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:30:15 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 16:30:15 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 16:30:15 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 16:30:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:30:15 INFO - Return code: 0 16:30:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:30:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:30:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:30:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:30:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:30:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 16:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 16:30:15 INFO - Using env: {'DISPLAY': ':0', 16:30:15 INFO - 'HOME': '/home/cltbld', 16:30:15 INFO - 'LANG': 'en_US.UTF-8', 16:30:15 INFO - 'LANGUAGE': 'en_US:en', 16:30:15 INFO - 'LOGNAME': 'cltbld', 16:30:15 INFO - 'MAIL': '/var/mail/cltbld', 16:30:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:30:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:30:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:30:15 INFO - 'MOZ_NO_REMOTE': '1', 16:30:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:30:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:30:15 INFO - 'NO_EM_RESTART': '1', 16:30:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:30:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:30:15 INFO - 'PWD': '/builds/slave/test', 16:30:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:30:15 INFO - 'SHELL': '/bin/bash', 16:30:15 INFO - 'SHLVL': '1', 16:30:15 INFO - 'TERM': 'linux', 16:30:15 INFO - 'TMOUT': '86400', 16:30:15 INFO - 'USER': 'cltbld', 16:30:15 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569', 16:30:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:30:15 INFO - '_': '/tools/buildbot/bin/python'} 16:30:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 16:30:16 INFO - mozversion application_buildid: 20160807150152 16:30:16 INFO - mozversion application_changeset: ad2be3aadc626da670b5d0dd60e19afe2108b25b 16:30:16 INFO - mozversion application_display_name: Nightly 16:30:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:30:16 INFO - mozversion application_name: Firefox 16:30:16 INFO - mozversion application_remotingname: firefox 16:30:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:30:16 INFO - mozversion application_vendor: Mozilla 16:30:16 INFO - mozversion application_version: 51.0a1 16:30:16 INFO - mozversion platform_buildid: 20160807150152 16:30:16 INFO - mozversion platform_changeset: ad2be3aadc626da670b5d0dd60e19afe2108b25b 16:30:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:30:16 INFO - mozversion platform_version: 51.0a1 16:30:16 INFO - using testdate: 1470612616 16:30:16 INFO - actual date: 1470612616 16:30:16 INFO - starting webserver on 'localhost:39156' 16:30:16 INFO - SUITE-START | Running 2 tests 16:30:16 INFO - TEST-START | dromaeo_css 16:30:16 INFO - operating with platform_type : linux_ 16:30:16 INFO - Initialising browser for dromaeo_css test... 16:30:16 INFO - TEST-INFO | started process 22986 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UVfsI/profile http://localhost:39156/getInfo.html) 16:30:20 INFO - PROCESS | 22986 | __metrics Screen width/height:1600/1200 16:30:20 INFO - PROCESS | 22986 | colorDepth:24 16:30:20 INFO - PROCESS | 22986 | Browser inner width/height: 1024/697 16:30:20 INFO - PROCESS | 22986 | __metrics 16:30:21 INFO - PROCESS | 22986 | 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" 16:30:21 INFO - PROCESS | 22986 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:30:22 INFO - TEST-INFO | 22986: exit 0 16:30:22 INFO - Browser initialized. 16:30:22 INFO - Running cycle 1/1 for dromaeo_css test... 16:30:22 INFO - TEST-INFO | started process 23068 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7UVfsI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:34 INFO - PROCESS | 23068 | RSS: Main: 157683712 16:30:34 INFO - PROCESS | 23068 | 16:31:25 INFO - PROCESS | 23068 | 1470612685208 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] 16:33:38 INFO - PROCESS | 23068 | Cycle 1(1): loaded http://localhost:39156/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39156/tests/dromaeo/cssquery-ext.html) 16:33:39 INFO - PROCESS | 23068 | RSS: Main: 246906880 16:33:39 INFO - PROCESS | 23068 | 16:36:42 INFO - PROCESS | 23068 | Cycle 1(1): loaded http://localhost:39156/tests/dromaeo/cssquery-ext.html (next: http://localhost:39156/tests/dromaeo/cssquery-jquery.html) 16:36:42 INFO - PROCESS | 23068 | RSS: Main: 247939072 16:36:42 INFO - PROCESS | 23068 | 16:39:45 INFO - PROCESS | 23068 | Cycle 1(1): loaded http://localhost:39156/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39156/tests/dromaeo/cssquery-mootools.html) 16:39:45 INFO - PROCESS | 23068 | RSS: Main: 231190528 16:39:45 INFO - PROCESS | 23068 | 16:42:47 INFO - PROCESS | 23068 | Cycle 1(1): loaded http://localhost:39156/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39156/tests/dromaeo/cssquery-prototype.html) 16:42:48 INFO - PROCESS | 23068 | RSS: Main: 225800192 16:42:48 INFO - PROCESS | 23068 | 16:45:51 INFO - PROCESS | 23068 | Cycle 1(1): loaded http://localhost:39156/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39156/tests/dromaeo/cssquery-yui.html) 16:45:52 INFO - PROCESS | 23068 | RSS: Main: 188956672 16:45:52 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | Cycle 1(1): loaded http://localhost:39156/tests/dromaeo/cssquery-yui.html (next: http://localhost:39156/tests/dromaeo/cssquery-dojo.html) 16:48:55 INFO - PROCESS | 23068 | RSS: Main: 277577728 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | __start_tp_report 16:48:55 INFO - PROCESS | 23068 | _x_x_mozilla_page_load 16:48:55 INFO - PROCESS | 23068 | _x_x_mozilla_page_load_details 16:48:55 INFO - PROCESS | 23068 | |i|pagename|runs| 16:48:55 INFO - PROCESS | 23068 | |0;dojo.html;2621.9737802621976;2632.749888760567;2651.1516314779274;2677.6519052523167;2681.7854571634257;4506.684532082755;4610.9538904610945;4728.976355118224;4730.526947305268;4829.951700482999;4728.739919304437;4778.187708089625;4783.35424717663;4818.397700287464;4856.39295088114;4305.741655500664;4369.584889435507;4447.577480139382;4503.482099558543;4572.954270457286;4456.79944402501;4503.48209955856;4524.660650451229;4528.366028755977;4538.047010127877;4209.663226941837;4233.618974292329;4243.066525364446;4309.310510318334;4384.057427653038;4319.50325712544;4328.805203765838;4368.890777730582;4442.400275962736;4449.577290157462;4313.590208930146;4328.04782947751;4362.738235705868;4371.235033869092;4382.693211475198;4554.726716397026;4765.761711914391;4771;4816.590589799871;4822.300766388889;5065.848024559232;5161.122609156415;5164.92252616211;5246.449122842085;5260.763265653055;5208.661437006583;5239.13554263546;5240.3973543042275;5246.685198888079;5254.579633629301;4587.77061146948;4826.0347930414055;4874.8050077997195;4882.340883980688;4883.560479556857;5335.973320133375;5371.435999220103;5408.080626293539;5427.213054107132;5428.701421421781;5178.145605974972;5181.637285390062;5188.092083885305;5191.5846732261325;5192.1173400522;4854.126257273654;5037.798488060436;5270.472952704776;5274.393444753826;5295.841124766263;5061.544460998528;5117;5130.897382052338;5157.690538567698;5177.0940085485645;5070.340855688811;5077.898442031139;5077.949220507821;5090.363704536933;5132.230165475134;4122.319817230124;4153.8338466461;4345.652347812167;4359.149965756647;4389.978050109793;3680.7791532508136;3747.3629482987412;3778.792166430873;3817.6182381762155;3832.7125465590193;4356.346548017823;4359.847405340861;4364.607185353333;4396.736195828261;4398.8020539075815;5101.158308878993;5105.668131571436;5106.259592359162;5112.130937740593;5148.897022059537;4157.688173387008;4343.869683909487;4358.193734159191;4433.889152771207;4435.689501734848;4594.150082234797;4596.103759766814;4601.381511683027;4632.698874573142;4639.86080417588;4748.596369308648;4777.092352453023;4781.497942716104;4794.2808578713475;4830.927536086891;4933.703977761203;5041.420236672683;5097.92353114711;5098.439171691037;5108.719020453911;4796.112719146969;4810.8797280068275;4813.15769740301;4850.345203397496;4855.1260773061185;5050.368703912011;5050.419201791694;5055.646104772778;5055.696658200372;5057.595392368528;4904.754762261944;5170.97414512925;5176.663516871391;5220.216967454912;5220.9738951305;5097.133487307093;5107.565856902205;5107.668001580034;5108.9233661495855;5118.206677964921;5109.744512774272;5114.028334616411;5117.360329958755;5121.129408000575;5148.150555158357;4627.005193883331;4926.556609905126;4936.703797772145;4973.229149481836;5035.496450355082;5120.078385890426;5120.231965205249;5122.718250496258;5122.897542049063;5149.510796474329;4985.077760614298;4990.6007519397635;4997.525235102659;5012.749362531786;5023.824166154167 16:48:55 INFO - PROCESS | 23068 | |1;ext.html;558.3857756467884;560.8962341966734;563.5547917145456;570.9914351284732;576.432214268945;28548.42903141936;38820;40056.19887602246;40207.59792402079;44130.338044929355;40055.59944400555;40533.797331013375;40615.390769138416;40618.187636247254;40634;7781.649825757854;7926.80182995424;7929.405294602899;7929.603519824014;7932.444728868982;7395.70417183312;9926.156276716487;10102.545385457635;10173.135283500911;10524.947375263151;10212.897871021307;10315.742106447362;10317.896821031769;10319.071283584475;10328.173746100294;10148.949255253714;10178.338408003456;10211.540480678388;10215.438150901697;10215.744606384826;10096.747581310452;10163.390196588229;10166;10169.9491502542;10173.59305627774;37295.25409491822;37563.81218093892;37601.24797504062;37679.24641507182;37684.24631507382;3014.8191108533556;3015.321552650636;3021.3202029543177;3028.1067085209916;3126.124685088179;28454;29744.55383169254;29779.40441191164;30373.848130759205;30560;3502.562179727534;3534.204803919123;3592.1019745063736;3670.3209906167444;3680.35593771089;244074;244129;245553.77223113948;246389;246820;28454.573181402295;28859.85570072136;28871;52945;53114.734426327625;33905.32189356199;33941.32117357639;33960;34029.489557656656;34141;12264.877351226552;12287.324197169062;12372.381380930918;12423.503059877503;12425.565105221454;9257.35198536096;9840.60637574489;10157.695269141937;10203.795924081476;10219.795604087876;7772.84454310922;7918.564478953597;8011.7195898144455;8017.4788638739465;8043.597820108971;3049.048608890312;3126.296583268792;3133.592632957701;3135.2788858562203;3148.165736079941;799.7800604833717;904.7557159567011;906.2704522859161;908.52756566585;936.5364144748393;25034;25755.38995731809;25879;26122.86938565295;26214.73785262123;952.8904176019707;986.161762501882;1019.261035749076;1024.6925922223452;1025.5795123999123;13958.441662333596;14327.140371577738;14435.711285774225;14727.337269822883;14787.630309242355;388636;392926;392944;394797;395079;25948.481030379287;26091.08681196149;26133.607995880076;26186.86906565493;26247;1104.7624760676492;1140.8745038045975;1177.1877404590805;1207.2756346192214;1223.473906220316;25216.49567008613;29574.75447430047;29581.704182958754;29845.403091937605;29923.850380747957;39690.20619587534;39974.2005159901;40006.799965999984;40009.39985900272;40302.59697402988;289784;290592;291617;291817;293402;25559.744402555738;25660;25954.740452595986;26202.213933582025;26364.472710546066;42198.367024495274;42405.363919540614;42650.36024459574;42735.7863210682;42842.78578607087;31987.200319992186;32088.197795055312;32441;32542.34915301728;33645.66354336425;36404.81797590995;36603.45094823633;36605;36682.44976325304;37209.813950930074;35541.82229088838;35690.64309356977;35691.46462803008;36928.81535592305;37820.62179378171;31678.208044799063;32916.177095571846;33791.4931276036;33969.830150849084;34245.14387140242;25566.744332556435;25786.87106564455;25793.742062579135;25879.61180582255;26463.33841653974 16:48:55 INFO - PROCESS | 23068 | |2;jquery.html;332.2408297041261;351.8469465782383;360.1338780233538;367.9889603311902;380.8686003328851;12796.616101516958;16214.18929053547;16536.586585335357;16875.831241687578;18024.188911498983;16459.177041147956;17570.736438953427;17673.204705788237;17773.55566110848;17826.910865445654;5044.092063428581;5070.771815268304;5207.817726379578;5213.947860521384;5217.7130257836;3815.2178803345246;5052.040112378655;5139.974300128512;5766.192733017381;6034.155218269446;6095.603785753934;6114.205153330061;6139.324674285825;6149.600275982069;6153.0155175171985;5921.763129474816;6095.725692343855;6106.847328816771;6155.50755939526;6158.969205153946;5198.792048318062;5378.435264297232;5382.865428364322;5394.487523685283;5396.244525766396;20490.692639610417;20755.481112972146;20766.792332076788;20797.792022079735;20800.5839883203;1966.783653798081;1997.7303064086452;2054.98972505138;2136.7863213678665;2405.579023670868;13048.934755326163;13541.59375218745;13625.114367566077;13734.587962361145;14230.217338046403;1754.8508376787988;1760.6390689908537;1768.2484943898792;1808.5297822566097;1815.546113471632;66438.99999999952;66760.66619666871;66823.3317666822;67635;68574;8504.31965442763;8578.78553036179;8814.427062240933;9563;9694.418334899929;12531.373431328397;12641.43113559892;12672.87327126717;12683.936580317039;12687.936560317139;6697.799066028027;6754.189497260262;6825.1127353443735;6829.931700683029;6942.271061538466;6313.463355614824;6414.775482858077;6505.577137486048;6584.8024559263295;6600.306967768315;5915.3788852170765;6098.603590766675;6103.23709536308;6143;6157.507399408037;1700.5748562859285;1725.6462425202803;1756.394044054799;1765.3027054313475;1790.8567314615061;679.4089142446104;825.3933358981144;872.1409411729442;892.3262936482914;957.7749228931195;17255.137243138044;17283.987613625162;18261.63476730458;18311.90844045771;18501.259949601867;829.340674164041;857.4812238595684;891.3136884598953;895.83427065993;957.167264479893;9981.850272245922;10356.326838755607;10547.894521054845;10589.470526473646;10660.307080039773;376661;377625.4543454667;385464;388550;389205;66525.6673716638;70275.64862175657;70298.64850675817;70450;71305.93041104388;886.8403687336341;908.336914052732;909.3043821476575;911.1753862754218;913.881195444588;15592.610184745472;15626.374945002528;15982.60043498922;16147.75778363349;16179;33980.49029264513;35304.823475882455;36727.816360919096;36759.44860827036;36891.26217475689;45380.77309613563;45761.542384575725;45823;47118.76440617775;47768.41736869818;7185.53294035886;7498.58757768328;7614.657340419583;7625.23747625233;7793.493422927491;11880.762384752084;12247.387630618612;12327.075469339656;12336.938315308365;12795.168314059556;10352.741181470523;10385.273030887765;10529.947350263199;10635.840462392916;10847.240693151403;9860;10209.846852297072;10383.117435017806;10534.36793792375;10551.314164579278;10244.487775611338;10355.43045132525;10589.523471443958;10807.945960270148;11219.55121795119;6448.613083215022;7245.38414234796;7263.963680181565;7366.116066072105;7436.367908727819;8257.339412847077;10137.442440665835;10157.695269141937;10451.111655509367;10690.144788416752 16:48:55 INFO - PROCESS | 23068 | |3;mootools.html;1347.1782212850158;1409.2319685771254;1474.144995902376;1628.5358672778261;1702.029842989495;7268.382187514061;7870.567118808463;7871.763847084582;7873.409494287937;7875.173106823787;6116.204893363856;7582.128055273645;7746.302832745052;7816.609169541514;7818.14000459949;2181.0621432783937;2203.856749311298;2204.173434961889;2207.348832094528;2210;2124.224657999827;2155.2025750472267;2182.618041842671;2182.770809065049;2184.421128400975;1763.8588912886937;2091.16353458617;2127.606392817334;2128.095559387254;2140.5825863956534;2137.561799831031;2167.9132834686593;2170.4248374180856;2186.5080356919725;2190.726159230096;2158.093600687715;2165.5127596290868;2166.39340984523;2167.8482506224573;2168;7836.921630783676;7840.176781437924;7897.921020789776;7900.249476299742;7901.644426000844;4522.706024108423;4567.771611419415;4643.141018911512;4650.720956742606;4658.091672123937;2733.3099969925734;2908.185708001763;2916.3584011517432;2923.2545700846363;2925.0786002409172;863.2058506174335;921.6129225725211;924.2652091587204;929.8140371925642;961.2406199102701;10504;10508.211884108723;10510.211734119972;10516.1061309788;10531.94734026325;3901.297766402075;4135.400366946776;4197.706160568731;4248.936265956013;4252.978735106367;9028.052054534312;9047.276217902552;9052.321075919332;9053.59258833354;9063.954680226556;4648.279516674921;4664.323673067386;4664.953350466452;4666.626669866402;4675.041616468616;4186.5604111568455;4235.724677895927;4236.110416812442;4239.427677263591;4240.978795106066;3591.7665351752053;3609.061643972586;3690.833912473945;3715.8142092895246;3739.270842185748;1095.6274866545414;1107.6510899066768;1128.8758236593967;1133.886611338876;1136.181948996721;568.8325938188776;721.9278072192739;805.7582725182422;830.3689196210923;849.4054162086564;5028;5203.427622961472;5428.565714742811;5433.809816656398;5522.502974732293;832.9708460203864;861.5390716395981;868.9174528419917;878.7012415778665;889.3818795936762;3274.05052534767;3499.3876071687355;3600.5139306193328;3684.613115622821;3723.7579557329227;9269.72190834276;9735.513224338754;9740.902590974141;9754.463504507321;9776.755581110529;9656;9656.179224765832;9664.03359664028;9693.273004524688;9701.854472182922;7275.163356213997;7423.925760742323;7440.069991251094;7440.85118297631;7449.329560339487;5126.53861152498;5214.426413094632;5239.973800130975;5311.548518375981;5512.035393806148;8743.693970710994;8755.343349248704;8925.419847709878;8928.508932008803;9007.414518056305;8955.820883582423;9029.683961061332;9147.954260228656;9202;9203.493807840632;6262.34245404226;6453.290138084901;6464.967675161783;6489.610623362424;6489.7404103835315;7508.361789247975;7557.470977031555;7565.621718914143;7570.242975702386;7572.4699271050495;6720.1599800025;6741.764038258833;6758.5269031167345;6822.965885170543;6941.34057264559;6690.19717633887;6769.93230067693;6770.44279815655;6781.491388145811;6799.354061364163;5852.4147585241135;6008.218931538872;6110.175126357884;6120.326764056039;6158.107074474266;4516.480604730498;4789.114013907439;4851.078295123915;4861.611071114373;4879.463259041431;4696.671233013656;4856.223004319292;4927.482614325444;4957.529034741694;4991.425986011655 16:48:55 INFO - PROCESS | 23068 | |4;prototype.html;278.46256724521663;295.82694123937495;296.3983114278016;296.6425457323924;308.3863112406312;4668.159731248374;4816.8554943351755;4859.927101093486;4944.283078953554;4946.455889852118;4671.813127474906;4794;4818.204996175636;4824.710517368961;4832.308691957813;3786.3563194256903;3786.8295926683227;3787.8295476703615;3794.260119276742;3802.4866643003256;3591.299696559172;3633.291508155911;3815.637514436124;3819.427085937104;3823.4456003879422;3783.9810800945957;3792.0709426190483;3797.7341586088983;3799.905002374935;3809.095452273863;3763.473113764075;3778.6599206071446;3780.3951367781297;3780.981095094507;3809.2381523695235;3327.916802079943;3346.7489938254485;3352.4636058230567;3355.4295769719206;3361.8319084045943;4611.331356953223;4721.394725793212;4737.905241895177;4748.715077095385;4763.49983251757;4307;4322.708964248953;4325.589069038437;4326.935095973563;4332.891677708051;5043.293938848564;5043.319151914481;5046.6719663221775;5050.545450909436;5053.8231161909525;3384.6953774160443;3538.43385058392;3649.94525082124;3663.56037275526;3678.2459595782802;5281.419043905167;5317.813876514342;5318.361796584435;5320.201969704536;5338.332708411449;3611.4831770656388;3633.9636603634153;3637.5453068366455;3652.433872749728;3653.94519082214;3689.8339574719203;3691.9446208306895;3694.131879008431;3700.870469533553;3703.5370578677666;4135.627793498599;4203.747775133441;4208.9789551052045;4240.724352917051;4241.915161696748;5061.620378471555;5075.416327122428;5077.746112694351;5081.7967281309075;5093.930274642366;1199.6204364980342;1227.4537830665447;1234.882686144815;1276.9170003949714;1295.1775622479793;821.9219174178444;836.9747059851754;852.4714676900361;879.6965047058754;880.7621942075732;4440.71135376206;4449.755263460541;4467.922416076475;4476.597106260452;4481.619062379734;5222.6083043771105;5224.947750522522;5225.216217567319;5240.873212259383;5246.580273578104;4300.569943005675;4521.79651915658;4536.659750518725;4539.978504836451;4546.40896683429;2779.4580056889117;2812.4093940272774;2819.3929460207487;2824.56219286011;2841.559558268472;4799.28010798383;4975.328330675385;4984.352034235526;4998.750062496933;5018.749062546858;5389.407165211824;5403.567714582824;5421.430749771217;5423.864403389947;5445.618806683494;4955.207166853358;4960.553550180501;4973.577245934064;4974.602031837444;5000.67495612784;5116.437191908813;5224.895502089861;5231.29377534028;5286.7356632169;5311.097113490793;5135.35808023997;5142.405854185215;5175.534201921845;5182.585393168612;5185;4895.681780684244;4921.474342953553;4926.605871530197;4943.530364615356;4955.6531042826655;3509.4034014218155;3716.312482190803;3730.0861288984374;3741.812909354467;3743.906402339854;5237.4598319814095;5252.660571554229;5259.7370131494035;5262.131748261647;5264.210368444611;5110.335656364798;5122.308488354024;5122.564582010571;5124.923126153036;5126.666766660155;4892.672982372022;5032.6225533084435;5075.949240507695;5120.897582048413;5127.897442051063;4797.496262892346;4941.777620007037;4968.205087186033;5003.774830132726;5017.1721665924715;5037.319961805109;5038.823641172541;5067.7212753297445;5068.26510156018;5140.074786538459;5045.520675535819;5047.924281135713;5050.419201791694;5050.72221027847;5057.342545469212 16:48:55 INFO - PROCESS | 23068 | |5;yui.html;582.6940856050572;648.3484098481026;685.6948657847257;704.5209257704762;732.8424388756416;12205.694857628552;12829.48682052718;12956.611301660954;13136.592580748329;13427;13542.593722188325;13616.931915340434;13856.307184640778;13861.584152475441;13881.514147004831;639.3127388057833;765.6516285090285;790.952542847431;796.74902405742;797.2107613462688;773.7678696391089;795.5544894858898;797.8563858505466;804.5293503300576;805.3919290935365;832.4880198677799;835.3317346123108;835.8119423129777;835.8829763833069;836.067784420373;669.5012215899186;831.9459235149726;832.2967092806568;832.8500869843426;832.8584140696096;594.678873408359;603.3393434189555;605.7758629307141;609.2080295615682;609.2475792396386;11764.882351176464;11787.115966302475;11803.055755539537;11815.527378904833;11823.763524729457;10144.695659130239;10289.434081125535;10527.473626318653;10928.781424371467;11141.275817071864;10400.583976640924;10776.137908967265;11025.558977640885;11039.72400689981;11396;3942.7943230100236;4036.535798383165;4064.8753712582998;4089.284375234322;4168.95776055986;63476.36523634797;64443.355566444676;64661.67669161624;65697;65888.3411165887;9388.90611093894;9657.46883921391;9725.416475011521;9836.901630983743;9847.704568862946;18832.05839708009;18833;18875.528111797314;19022.334218302294;19143.712844307345;6577.440917521969;6636.402723754885;6705.061291419205;6767.492438067165;6962.860742785116;3436.347094052122;3515.2442224921765;3538.221591249921;3581.8925432237074;3614.313280476701;2640.683118025849;2726.8091233613854;2736.616873637692;2739.02764518597;2740.8081434299806;480.1932752974966;480.68274938540395;481.93252944587084;491.5723320710933;494.4733858440749;530.2708775433779;542.5442628192337;557.7211394302849;562.5836880708247;567.3872218004545;7636.618169091523;7733.131159969647;7737.187595302525;7859.331956783737;7935.365170786324;392.40942381715337;431.9848805291862;455.39432554702165;485.4247716456011;526.6366207316939;3727.38498147808;3810.1046254130256;3975.8012099394914;4005.9799701001307;4295.183915056129;94753.5262323684;100087.49956250317;101000;101351.49324253331;106357;58505.70747146237;85801.63279485794;88981;90094.54952725195;90941.54529227312;63973;79678;79843.60078199572;79952.60023699961;80465;7754.495957762728;7996.880046799186;8076.676932922617;8183.468074575134;8216.26053655174;37316.81341593383;43531.99738857837;43930.34104488499;44700.32949505825;44973.55026449694;37704.81147594354;38545;59634;66569;67289.32710673027;10594.364338139736;10627.468626568796;10662.89337106619;10676.73308167271;10696.465176741287;12842.357882106044;12913.741725165632;12924.224546527239;13493.662658433617;14094.154350738989;11437.150538438927;11632.476538555617;11691.407358012595;11746.29522228669;12177.634670959886;10624.628138015132;11221.66335009951;11284.492197850952;11325.603603873826;11348.489317980546;11479.4260286987;12009.579664711693;12130.818037729265;12135.939320303341;12278.201916875374;10077.344972576759;10137.746556336151;10180.609352507356;10345.172386209235;10416.687499375032;9939.254555908192;10022.3485473444;10088.899111008795;10114.241431892784;10722.356658600509 16:48:55 INFO - PROCESS | 23068 | __end_tp_report 16:48:55 INFO - PROCESS | 23068 | __start_cc_report 16:48:55 INFO - PROCESS | 23068 | _x_x_mozilla_cycle_collect,1024 16:48:55 INFO - PROCESS | 23068 | __end_cc_report 16:48:55 INFO - PROCESS | 23068 | __startTimestamp1470613735625__endTimestamp 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | ------- Summary: start ------- 16:48:55 INFO - PROCESS | 23068 | Number of tests: 6 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | [#0] dojo.html Cycles:165 Average:4746.09 Median:4855.13 stddev:528.55 (10.9%) stddev-sans-first:503.22 16:48:55 INFO - PROCESS | 23068 | Values: 2622.0 2632.7 2651.2 2677.7 2681.8 4506.7 4611.0 4729.0 4730.5 4830.0 4728.7 4778.2 4783.4 4818.4 4856.4 4305.7 4369.6 4447.6 4503.5 4573.0 4456.8 4503.5 4524.7 4528.4 4538.0 4209.7 4233.6 4243.1 4309.3 4384.1 4319.5 4328.8 4368.9 4442.4 4449.6 4313.6 4328.0 4362.7 4371.2 4382.7 4554.7 4765.8 4771.0 4816.6 4822.3 5065.8 5161.1 5164.9 5246.4 5260.8 5208.7 5239.1 5240.4 5246.7 5254.6 4587.8 4826.0 4874.8 4882.3 4883.6 5336.0 5371.4 5408.1 5427.2 5428.7 5178.1 5181.6 5188.1 5191.6 5192.1 4854.1 5037.8 5270.5 5274.4 5295.8 5061.5 5117.0 5130.9 5157.7 5177.1 5070.3 5077.9 5077.9 5090.4 5132.2 4122.3 4153.8 4345.7 4359.1 4390.0 3680.8 3747.4 3778.8 3817.6 3832.7 4356.3 4359.8 4364.6 4396.7 4398.8 5101.2 5105.7 5106.3 5112.1 5148.9 4157.7 4343.9 4358.2 4433.9 4435.7 4594.2 4596.1 4601.4 4632.7 4639.9 4748.6 4777.1 4781.5 4794.3 4830.9 4933.7 5041.4 5097.9 5098.4 5108.7 4796.1 4810.9 4813.2 4850.3 4855.1 5050.4 5050.4 5055.6 5055.7 5057.6 4904.8 5171.0 5176.7 5220.2 5221.0 5097.1 5107.6 5107.7 5108.9 5118.2 5109.7 5114.0 5117.4 5121.1 5148.2 4627.0 4926.6 4936.7 4973.2 5035.5 5120.1 5120.2 5122.7 5122.9 5149.5 4985.1 4990.6 4997.5 5012.7 5023.8 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | [#1] ext.html Cycles:180 Average:44684.77 Median:3134.44 stddev:83340.13 (2658.9%) stddev-sans-first:83508.07 16:48:55 INFO - PROCESS | 23068 | Values: 558.4 560.9 563.6 571.0 576.4 28548.4 38820.0 40056.2 40207.6 44130.3 40055.6 40533.8 40615.4 40618.2 40634.0 7781.6 7926.8 7929.4 7929.6 7932.4 7395.7 9926.2 10102.5 10173.1 10524.9 10212.9 10315.7 10317.9 10319.1 10328.2 10148.9 10178.3 10211.5 10215.4 10215.7 10096.7 10163.4 10166.0 10169.9 10173.6 37295.3 37563.8 37601.2 37679.2 37684.2 3014.8 3015.3 3021.3 3028.1 3126.1 28454.0 29744.6 29779.4 30373.8 30560.0 3502.6 3534.2 3592.1 3670.3 3680.4 244074.0 244129.0 245553.8 246389.0 246820.0 28454.6 28859.9 28871.0 52945.0 53114.7 33905.3 33941.3 33960.0 34029.5 34141.0 12264.9 12287.3 12372.4 12423.5 12425.6 9257.4 9840.6 10157.7 10203.8 10219.8 7772.8 7918.6 8011.7 8017.5 8043.6 3049.0 3126.3 3133.6 3135.3 3148.2 799.8 904.8 906.3 908.5 936.5 25034.0 25755.4 25879.0 26122.9 26214.7 952.9 986.2 1019.3 1024.7 1025.6 13958.4 14327.1 14435.7 14727.3 14787.6 388636.0 392926.0 392944.0 394797.0 395079.0 25948.5 26091.1 26133.6 26186.9 26247.0 1104.8 1140.9 1177.2 1207.3 1223.5 25216.5 29574.8 29581.7 29845.4 29923.9 39690.2 39974.2 40006.8 40009.4 40302.6 289784.0 290592.0 291617.0 291817.0 293402.0 25559.7 25660.0 25954.7 26202.2 26364.5 42198.4 42405.4 42650.4 42735.8 42842.8 31987.2 32088.2 32441.0 32542.3 33645.7 36404.8 36603.5 36605.0 36682.4 37209.8 35541.8 35690.6 35691.5 36928.8 37820.6 31678.2 32916.2 33791.5 33969.8 34245.1 25566.7 25786.9 25793.7 25879.6 26463.3 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | [#2] jquery.html Cycles:180 Average:23916.93 Median:387007.00 stddev:63077.48 (16.3%) stddev-sans-first:63229.57 16:48:55 INFO - PROCESS | 23068 | Values: 332.2 351.8 360.1 368.0 380.9 12796.6 16214.2 16536.6 16875.8 18024.2 16459.2 17570.7 17673.2 17773.6 17826.9 5044.1 5070.8 5207.8 5213.9 5217.7 3815.2 5052.0 5140.0 5766.2 6034.2 6095.6 6114.2 6139.3 6149.6 6153.0 5921.8 6095.7 6106.8 6155.5 6159.0 5198.8 5378.4 5382.9 5394.5 5396.2 20490.7 20755.5 20766.8 20797.8 20800.6 1966.8 1997.7 2055.0 2136.8 2405.6 13048.9 13541.6 13625.1 13734.6 14230.2 1754.9 1760.6 1768.2 1808.5 1815.5 66439.0 66760.7 66823.3 67635.0 68574.0 8504.3 8578.8 8814.4 9563.0 9694.4 12531.4 12641.4 12672.9 12683.9 12687.9 6697.8 6754.2 6825.1 6829.9 6942.3 6313.5 6414.8 6505.6 6584.8 6600.3 5915.4 6098.6 6103.2 6143.0 6157.5 1700.6 1725.6 1756.4 1765.3 1790.9 679.4 825.4 872.1 892.3 957.8 17255.1 17284.0 18261.6 18311.9 18501.3 829.3 857.5 891.3 895.8 957.2 9981.9 10356.3 10547.9 10589.5 10660.3 376661.0 377625.5 385464.0 388550.0 389205.0 66525.7 70275.6 70298.6 70450.0 71305.9 886.8 908.3 909.3 911.2 913.9 15592.6 15626.4 15982.6 16147.8 16179.0 33980.5 35304.8 36727.8 36759.4 36891.3 45380.8 45761.5 45823.0 47118.8 47768.4 7185.5 7498.6 7614.7 7625.2 7793.5 11880.8 12247.4 12327.1 12336.9 12795.2 10352.7 10385.3 10529.9 10635.8 10847.2 9860.0 10209.8 10383.1 10534.4 10551.3 10244.5 10355.4 10589.5 10807.9 11219.6 6448.6 7245.4 7264.0 7366.1 7436.4 8257.3 10137.4 10157.7 10451.1 10690.1 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | [#3] mootools.html Cycles:180 Average:5135.25 Median:5370.06 stddev:2873.15 (53.5%) stddev-sans-first:2867.11 16:48:55 INFO - PROCESS | 23068 | Values: 1347.2 1409.2 1474.1 1628.5 1702.0 7268.4 7870.6 7871.8 7873.4 7875.2 6116.2 7582.1 7746.3 7816.6 7818.1 2181.1 2203.9 2204.2 2207.3 2210.0 2124.2 2155.2 2182.6 2182.8 2184.4 1763.9 2091.2 2127.6 2128.1 2140.6 2137.6 2167.9 2170.4 2186.5 2190.7 2158.1 2165.5 2166.4 2167.8 2168.0 7836.9 7840.2 7897.9 7900.2 7901.6 4522.7 4567.8 4643.1 4650.7 4658.1 2733.3 2908.2 2916.4 2923.3 2925.1 863.2 921.6 924.3 929.8 961.2 10504.0 10508.2 10510.2 10516.1 10531.9 3901.3 4135.4 4197.7 4248.9 4253.0 9028.1 9047.3 9052.3 9053.6 9064.0 4648.3 4664.3 4665.0 4666.6 4675.0 4186.6 4235.7 4236.1 4239.4 4241.0 3591.8 3609.1 3690.8 3715.8 3739.3 1095.6 1107.7 1128.9 1133.9 1136.2 568.8 721.9 805.8 830.4 849.4 5028.0 5203.4 5428.6 5433.8 5522.5 833.0 861.5 868.9 878.7 889.4 3274.1 3499.4 3600.5 3684.6 3723.8 9269.7 9735.5 9740.9 9754.5 9776.8 9656.0 9656.2 9664.0 9693.3 9701.9 7275.2 7423.9 7440.1 7440.9 7449.3 5126.5 5214.4 5240.0 5311.5 5512.0 8743.7 8755.3 8925.4 8928.5 9007.4 8955.8 9029.7 9148.0 9202.0 9203.5 6262.3 6453.3 6465.0 6489.6 6489.7 7508.4 7557.5 7565.6 7570.2 7572.5 6720.2 6741.8 6758.5 6823.0 6941.3 6690.2 6769.9 6770.4 6781.5 6799.4 5852.4 6008.2 6110.2 6120.3 6158.1 4516.5 4789.1 4851.1 4861.6 4879.5 4696.7 4856.2 4927.5 4957.5 4991.4 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | [#4] prototype.html Cycles:180 Average:4220.68 Median:4817.53 stddev:1236.18 (25.7%) stddev-sans-first:1203.71 16:48:55 INFO - PROCESS | 23068 | Values: 278.5 295.8 296.4 296.6 308.4 4668.2 4816.9 4859.9 4944.3 4946.5 4671.8 4794.0 4818.2 4824.7 4832.3 3786.4 3786.8 3787.8 3794.3 3802.5 3591.3 3633.3 3815.6 3819.4 3823.4 3784.0 3792.1 3797.7 3799.9 3809.1 3763.5 3778.7 3780.4 3781.0 3809.2 3327.9 3346.7 3352.5 3355.4 3361.8 4611.3 4721.4 4737.9 4748.7 4763.5 4307.0 4322.7 4325.6 4326.9 4332.9 5043.3 5043.3 5046.7 5050.5 5053.8 3384.7 3538.4 3649.9 3663.6 3678.2 5281.4 5317.8 5318.4 5320.2 5338.3 3611.5 3634.0 3637.5 3652.4 3653.9 3689.8 3691.9 3694.1 3700.9 3703.5 4135.6 4203.7 4209.0 4240.7 4241.9 5061.6 5075.4 5077.7 5081.8 5093.9 1199.6 1227.5 1234.9 1276.9 1295.2 821.9 837.0 852.5 879.7 880.8 4440.7 4449.8 4467.9 4476.6 4481.6 5222.6 5224.9 5225.2 5240.9 5246.6 4300.6 4521.8 4536.7 4540.0 4546.4 2779.5 2812.4 2819.4 2824.6 2841.6 4799.3 4975.3 4984.4 4998.8 5018.7 5389.4 5403.6 5421.4 5423.9 5445.6 4955.2 4960.6 4973.6 4974.6 5000.7 5116.4 5224.9 5231.3 5286.7 5311.1 5135.4 5142.4 5175.5 5182.6 5185.0 4895.7 4921.5 4926.6 4943.5 4955.7 3509.4 3716.3 3730.1 3741.8 3743.9 5237.5 5252.7 5259.7 5262.1 5264.2 5110.3 5122.3 5122.6 5124.9 5126.7 4892.7 5032.6 5075.9 5120.9 5127.9 4797.5 4941.8 4968.2 5003.8 5017.2 5037.3 5038.8 5067.7 5068.3 5140.1 5045.5 5047.9 5050.4 5050.7 5057.3 16:48:55 INFO - PROCESS | 23068 | 16:48:55 INFO - PROCESS | 23068 | [#5] yui.html Cycles:180 Average:17574.16 Median:4232.07 stddev:25587.96 (604.6%) stddev-sans-first:25627.93 16:48:55 INFO - PROCESS | 23068 | Values: 582.7 648.3 685.7 704.5 732.8 12205.7 12829.5 12956.6 13136.6 13427.0 13542.6 13616.9 13856.3 13861.6 13881.5 639.3 765.7 791.0 796.7 797.2 773.8 795.6 797.9 804.5 805.4 832.5 835.3 835.8 835.9 836.1 669.5 831.9 832.3 832.9 832.9 594.7 603.3 605.8 609.2 609.2 11764.9 11787.1 11803.1 11815.5 11823.8 10144.7 10289.4 10527.5 10928.8 11141.3 10400.6 10776.1 11025.6 11039.7 11396.0 3942.8 4036.5 4064.9 4089.3 4169.0 63476.4 64443.4 64661.7 65697.0 65888.3 9388.9 9657.5 9725.4 9836.9 9847.7 18832.1 18833.0 18875.5 19022.3 19143.7 6577.4 6636.4 6705.1 6767.5 6962.9 3436.3 3515.2 3538.2 3581.9 3614.3 2640.7 2726.8 2736.6 2739.0 2740.8 480.2 480.7 481.9 491.6 494.5 530.3 542.5 557.7 562.6 567.4 7636.6 7733.1 7737.2 7859.3 7935.4 392.4 432.0 455.4 485.4 526.6 3727.4 3810.1 3975.8 4006.0 4295.2 94753.5 100087.5 101000.0 101351.5 106357.0 58505.7 85801.6 88981.0 90094.5 90941.5 63973.0 79678.0 79843.6 79952.6 80465.0 7754.5 7996.9 8076.7 8183.5 8216.3 37316.8 43532.0 43930.3 44700.3 44973.6 37704.8 38545.0 59634.0 66569.0 67289.3 10594.4 10627.5 10662.9 10676.7 10696.5 12842.4 12913.7 12924.2 13493.7 14094.2 11437.2 11632.5 11691.4 11746.3 12177.6 10624.6 11221.7 11284.5 11325.6 11348.5 11479.4 12009.6 12130.8 12135.9 12278.2 10077.3 10137.7 10180.6 10345.2 10416.7 9939.3 10022.3 10088.9 10114.2 10722.4 16:48:55 INFO - PROCESS | 23068 | -------- Summary: end -------- 16:48:55 INFO - PROCESS | 23068 | 16:48:56 INFO - TEST-INFO | 23068: exit 0 16:48:56 INFO - TEST-OK | dromaeo_css | took 1120383ms 16:48:56 INFO - TEST-START | kraken 16:48:56 INFO - operating with platform_type : linux_ 16:48:56 INFO - Initialising browser for kraken test... 16:48:56 INFO - TEST-INFO | started process 23258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps8HBN1/profile http://localhost:39156/getInfo.html) 16:49:00 INFO - PROCESS | 23258 | __metrics Screen width/height:1600/1200 16:49:00 INFO - PROCESS | 23258 | colorDepth:24 16:49:00 INFO - PROCESS | 23258 | Browser inner width/height: 1024/697 16:49:00 INFO - PROCESS | 23258 | __metrics 16:49:01 INFO - PROCESS | 23258 | 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" 16:49:01 INFO - PROCESS | 23258 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:49:02 INFO - TEST-INFO | 23258: exit 0 16:49:02 INFO - Browser initialized. 16:49:02 INFO - Running cycle 1/1 for kraken test... 16:49:02 INFO - TEST-INFO | started process 23338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps8HBN1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:49:14 INFO - PROCESS | 23338 | RSS: Main: 157986816 16:49:14 INFO - PROCESS | 23338 | 16:49:17 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39156/tests/kraken/driver.html?testName=audio-beat-detection) 16:49:18 INFO - PROCESS | 23338 | RSS: Main: 214011904 16:49:18 INFO - PROCESS | 23338 | 16:49:20 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39156/tests/kraken/driver.html?testName=audio-dft) 16:49:21 INFO - PROCESS | 23338 | RSS: Main: 188973056 16:49:21 INFO - PROCESS | 23338 | 16:49:24 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39156/tests/kraken/driver.html?testName=audio-fft) 16:49:25 INFO - PROCESS | 23338 | RSS: Main: 272089088 16:49:25 INFO - PROCESS | 23338 | 16:49:27 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39156/tests/kraken/driver.html?testName=audio-oscillator) 16:49:28 INFO - PROCESS | 23338 | RSS: Main: 180924416 16:49:28 INFO - PROCESS | 23338 | 16:49:30 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39156/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:49:30 INFO - PROCESS | 23338 | RSS: Main: 184934400 16:49:30 INFO - PROCESS | 23338 | 16:49:34 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39156/tests/kraken/driver.html?testName=imaging-darkroom) 16:49:34 INFO - PROCESS | 23338 | RSS: Main: 221310976 16:49:34 INFO - PROCESS | 23338 | 16:49:38 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39156/tests/kraken/driver.html?testName=imaging-desaturate) 16:49:38 INFO - PROCESS | 23338 | RSS: Main: 215265280 16:49:38 INFO - PROCESS | 23338 | 16:49:41 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39156/tests/kraken/driver.html?testName=json-parse-financial) 16:49:42 INFO - PROCESS | 23338 | RSS: Main: 218320896 16:49:42 INFO - PROCESS | 23338 | 16:49:44 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39156/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:49:44 INFO - PROCESS | 23338 | RSS: Main: 184455168 16:49:44 INFO - PROCESS | 23338 | 16:49:46 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:49:47 INFO - PROCESS | 23338 | RSS: Main: 189403136 16:49:47 INFO - PROCESS | 23338 | 16:49:49 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:49:50 INFO - PROCESS | 23338 | RSS: Main: 193323008 16:49:50 INFO - PROCESS | 23338 | 16:49:52 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:49:53 INFO - PROCESS | 23338 | RSS: Main: 189743104 16:49:53 INFO - PROCESS | 23338 | 16:49:56 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:49:57 INFO - PROCESS | 23338 | RSS: Main: 192090112 16:49:57 INFO - PROCESS | 23338 | 16:49:58 INFO - PROCESS | 23338 | Cycle 1(1): loaded http://localhost:39156/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39156/tests/kraken/driver.html?testName=ai-astar) 16:49:59 INFO - PROCESS | 23338 | RSS: Main: 243412992 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | __start_tp_report 16:49:59 INFO - PROCESS | 23338 | _x_x_mozilla_page_load 16:49:59 INFO - PROCESS | 23338 | _x_x_mozilla_page_load_details 16:49:59 INFO - PROCESS | 23338 | |i|pagename|runs| 16:49:59 INFO - PROCESS | 23338 | |0;ai-astar;103;113;112;116;113;114;113;113;113;112 16:49:59 INFO - PROCESS | 23338 | |1;audio-beat-detection;154;123;128;186;128;129;146;126;173;122 16:49:59 INFO - PROCESS | 23338 | |2;audio-dft;161;160;161;160;162;161;161;161;161;161 16:49:59 INFO - PROCESS | 23338 | |3;audio-fft;78;75;75;108;77;77;76;74;110;77 16:49:59 INFO - PROCESS | 23338 | |4;audio-oscillator;85;86;138;85;85;85;118;85;85;129 16:49:59 INFO - PROCESS | 23338 | |5;imaging-gaussian-blur;116;116;117;116;117;116;117;117;117;116 16:49:59 INFO - PROCESS | 23338 | |6;imaging-darkroom;145;147;147;146;146;146;145;145;145;145 16:49:59 INFO - PROCESS | 23338 | |7;imaging-desaturate;95;95;95;95;99;95;95;96;95;95 16:49:59 INFO - PROCESS | 23338 | |8;json-parse-financial;64;64;64;63;62;64;65;64;63;62 16:49:59 INFO - PROCESS | 23338 | |9;json-stringify-tinderbox;65;65;65;64;65;64;65;65;66;64 16:49:59 INFO - PROCESS | 23338 | |10;stanford-crypto-aes;79;80;82;79;79;80;80;89;85;81 16:49:59 INFO - PROCESS | 23338 | |11;stanford-crypto-ccm;129;131;134;133;131;131;131;141;150;133 16:49:59 INFO - PROCESS | 23338 | |12;stanford-crypto-pbkdf2;173;172;171;168;165;169;167;170;165;168 16:49:59 INFO - PROCESS | 23338 | |13;stanford-crypto-sha256-iterative;67;67;67;66;72;71;67;67;66;67 16:49:59 INFO - PROCESS | 23338 | __end_tp_report 16:49:59 INFO - PROCESS | 23338 | __start_cc_report 16:49:59 INFO - PROCESS | 23338 | _x_x_mozilla_cycle_collect,1936 16:49:59 INFO - PROCESS | 23338 | __end_cc_report 16:49:59 INFO - PROCESS | 23338 | __startTimestamp1470613799036__endTimestamp 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | ------- Summary: start ------- 16:49:59 INFO - PROCESS | 23338 | Number of tests: 14 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.00 stddev:3.43 (3.0%) stddev-sans-first:1.20 16:49:59 INFO - PROCESS | 23338 | Values: 103.0 113.0 112.0 116.0 113.0 114.0 113.0 113.0 113.0 112.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#1] audio-beat-detection Cycles:10 Average:141.50 Median:137.50 stddev:22.69 (16.5%) stddev-sans-first:23.61 16:49:59 INFO - PROCESS | 23338 | Values: 154.0 123.0 128.0 186.0 128.0 129.0 146.0 126.0 173.0 122.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 16:49:59 INFO - PROCESS | 23338 | Values: 161.0 160.0 161.0 160.0 162.0 161.0 161.0 161.0 161.0 161.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#3] audio-fft Cycles:10 Average:82.70 Median:76.50 stddev:13.92 (18.2%) stddev-sans-first:14.66 16:49:59 INFO - PROCESS | 23338 | Values: 78.0 75.0 75.0 108.0 77.0 77.0 76.0 74.0 110.0 77.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#4] audio-oscillator Cycles:10 Average:98.10 Median:85.00 stddev:21.39 (25.2%) stddev-sans-first:22.16 16:49:59 INFO - PROCESS | 23338 | Values: 85.0 86.0 138.0 85.0 85.0 85.0 118.0 85.0 85.0 129.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 16:49:59 INFO - PROCESS | 23338 | Values: 116.0 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 116.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 16:49:59 INFO - PROCESS | 23338 | Values: 145.0 147.0 147.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#7] imaging-desaturate Cycles:10 Average:95.50 Median:95.00 stddev:1.27 (1.3%) stddev-sans-first:1.33 16:49:59 INFO - PROCESS | 23338 | Values: 95.0 95.0 95.0 95.0 99.0 95.0 95.0 96.0 95.0 95.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:64.00 stddev:0.97 (1.5%) stddev-sans-first:1.01 16:49:59 INFO - PROCESS | 23338 | Values: 64.0 64.0 64.0 63.0 62.0 64.0 65.0 64.0 63.0 62.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 16:49:59 INFO - PROCESS | 23338 | Values: 65.0 65.0 65.0 64.0 65.0 64.0 65.0 65.0 66.0 64.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:80.50 stddev:3.24 (4.0%) stddev-sans-first:3.32 16:49:59 INFO - PROCESS | 23338 | Values: 79.0 80.0 82.0 79.0 79.0 80.0 80.0 89.0 85.0 81.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#11] stanford-crypto-ccm Cycles:10 Average:134.40 Median:133.00 stddev:6.38 (4.8%) stddev-sans-first:6.46 16:49:59 INFO - PROCESS | 23338 | Values: 129.0 131.0 134.0 133.0 131.0 131.0 131.0 141.0 150.0 133.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.80 Median:169.50 stddev:2.74 (1.6%) stddev-sans-first:2.45 16:49:59 INFO - PROCESS | 23338 | Values: 173.0 172.0 171.0 168.0 165.0 169.0 167.0 170.0 165.0 168.0 16:49:59 INFO - PROCESS | 23338 | 16:49:59 INFO - PROCESS | 23338 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:67.00 stddev:2.06 (3.1%) stddev-sans-first:2.17 16:49:59 INFO - PROCESS | 23338 | Values: 67.0 67.0 67.0 66.0 72.0 71.0 67.0 67.0 66.0 67.0 16:49:59 INFO - PROCESS | 23338 | -------- Summary: end -------- 16:49:59 INFO - PROCESS | 23338 | 16:50:00 INFO - TEST-INFO | 23338: exit 0 16:50:00 INFO - TEST-OK | kraken | took 63681ms 16:50:00 INFO - SUITE-END | took 1184s 16:50:00 INFO - Completed test suite (00:19:44) 16:50:00 INFO - javascript benchmark 16:50:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2621.9737802621976, 2632.749888760567, 2651.1516314779274, 2677.6519052523167, 2681.7854571634257, 4506.684532082755, 4610.9538904610945, 4728.976355118224, 4730.526947305268, 4829.951700482999, 4728.739919304437, 4778.187708089625, 4783.35424717663, 4818.397700287464, 4856.39295088114, 4305.741655500664, 4369.584889435507, 4447.577480139382, 4503.482099558543, 4572.954270457286, 4456.79944402501, 4503.48209955856, 4524.660650451229, 4528.366028755977, 4538.047010127877, 4209.663226941837, 4233.618974292329, 4243.066525364446, 4309.310510318334, 4384.057427653038, 4319.50325712544, 4328.805203765838, 4368.890777730582, 4442.400275962736, 4449.577290157462, 4313.590208930146, 4328.04782947751, 4362.738235705868, 4371.235033869092, 4382.693211475198, 4554.726716397026, 4765.761711914391, 4771.0, 4816.590589799871, 4822.300766388889, 5065.848024559232, 5161.122609156415, 5164.92252616211, 5246.449122842085, 5260.763265653055, 5208.661437006583, 5239.13554263546, 5240.3973543042275, 5246.685198888079, 5254.579633629301, 4587.77061146948, 4826.0347930414055, 4874.8050077997195, 4882.340883980688, 4883.560479556857, 5335.973320133375, 5371.435999220103, 5408.080626293539, 5427.213054107132, 5428.701421421781, 5178.145605974972, 5181.637285390062, 5188.092083885305, 5191.5846732261325, 5192.1173400522, 4854.126257273654, 5037.798488060436, 5270.472952704776, 5274.393444753826, 5295.841124766263, 5061.544460998528, 5117.0, 5130.897382052338, 5157.690538567698, 5177.0940085485645, 5070.340855688811, 5077.898442031139, 5077.949220507821, 5090.363704536933, 5132.230165475134, 4122.319817230124, 4153.8338466461, 4345.652347812167, 4359.149965756647, 4389.978050109793, 3680.7791532508136, 3747.3629482987412, 3778.792166430873, 3817.6182381762155, 3832.7125465590193, 4356.346548017823, 4359.847405340861, 4364.607185353333, 4396.736195828261, 4398.8020539075815, 5101.158308878993, 5105.668131571436, 5106.259592359162, 5112.130937740593, 5148.897022059537, 4157.688173387008, 4343.869683909487, 4358.193734159191, 4433.889152771207, 4435.689501734848, 4594.150082234797, 4596.103759766814, 4601.381511683027, 4632.698874573142, 4639.86080417588, 4748.596369308648, 4777.092352453023, 4781.497942716104, 4794.2808578713475, 4830.927536086891, 4933.703977761203, 5041.420236672683, 5097.92353114711, 5098.439171691037, 5108.719020453911, 4796.112719146969, 4810.8797280068275, 4813.15769740301, 4850.345203397496, 4855.1260773061185, 5050.368703912011, 5050.419201791694, 5055.646104772778, 5055.696658200372, 5057.595392368528, 4904.754762261944, 5170.97414512925, 5176.663516871391, 5220.216967454912, 5220.9738951305, 5097.133487307093, 5107.565856902205, 5107.668001580034, 5108.9233661495855, 5118.206677964921, 5109.744512774272, 5114.028334616411, 5117.360329958755, 5121.129408000575, 5148.150555158357, 4627.005193883331, 4926.556609905126, 4936.703797772145, 4973.229149481836, 5035.496450355082, 5120.078385890426, 5120.231965205249, 5122.718250496258, 5122.897542049063, 5149.510796474329, 4985.077760614298, 4990.6007519397635, 4997.525235102659, 5012.749362531786, 5023.824166154167], "value": 4710.980847859181, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [558.3857756467884, 560.8962341966734, 563.5547917145456, 570.9914351284732, 576.432214268945, 28548.42903141936, 38820.0, 40056.19887602246, 40207.59792402079, 44130.338044929355, 40055.59944400555, 40533.797331013375, 40615.390769138416, 40618.187636247254, 40634.0, 7781.649825757854, 7926.80182995424, 7929.405294602899, 7929.603519824014, 7932.444728868982, 7395.70417183312, 9926.156276716487, 10102.545385457635, 10173.135283500911, 10524.947375263151, 10212.897871021307, 10315.742106447362, 10317.896821031769, 10319.071283584475, 10328.173746100294, 10148.949255253714, 10178.338408003456, 10211.540480678388, 10215.438150901697, 10215.744606384826, 10096.747581310452, 10163.390196588229, 10166.0, 10169.9491502542, 10173.59305627774, 37295.25409491822, 37563.81218093892, 37601.24797504062, 37679.24641507182, 37684.24631507382, 3014.8191108533556, 3015.321552650636, 3021.3202029543177, 3028.1067085209916, 3126.124685088179, 28454.0, 29744.55383169254, 29779.40441191164, 30373.848130759205, 30560.0, 3502.562179727534, 3534.204803919123, 3592.1019745063736, 3670.3209906167444, 3680.35593771089, 244074.0, 244129.0, 245553.77223113948, 246389.0, 246820.0, 28454.573181402295, 28859.85570072136, 28871.0, 52945.0, 53114.734426327625, 33905.32189356199, 33941.32117357639, 33960.0, 34029.489557656656, 34141.0, 12264.877351226552, 12287.324197169062, 12372.381380930918, 12423.503059877503, 12425.565105221454, 9257.35198536096, 9840.60637574489, 10157.695269141937, 10203.795924081476, 10219.795604087876, 7772.84454310922, 7918.564478953597, 8011.7195898144455, 8017.4788638739465, 8043.597820108971, 3049.048608890312, 3126.296583268792, 3133.592632957701, 3135.2788858562203, 3148.165736079941, 799.7800604833717, 904.7557159567011, 906.2704522859161, 908.52756566585, 936.5364144748393, 25034.0, 25755.38995731809, 25879.0, 26122.86938565295, 26214.73785262123, 952.8904176019707, 986.161762501882, 1019.261035749076, 1024.6925922223452, 1025.5795123999123, 13958.441662333596, 14327.140371577738, 14435.711285774225, 14727.337269822883, 14787.630309242355, 388636.0, 392926.0, 392944.0, 394797.0, 395079.0, 25948.481030379287, 26091.08681196149, 26133.607995880076, 26186.86906565493, 26247.0, 1104.7624760676492, 1140.8745038045975, 1177.1877404590805, 1207.2756346192214, 1223.473906220316, 25216.49567008613, 29574.75447430047, 29581.704182958754, 29845.403091937605, 29923.850380747957, 39690.20619587534, 39974.2005159901, 40006.799965999984, 40009.39985900272, 40302.59697402988, 289784.0, 290592.0, 291617.0, 291817.0, 293402.0, 25559.744402555738, 25660.0, 25954.740452595986, 26202.213933582025, 26364.472710546066, 42198.367024495274, 42405.363919540614, 42650.36024459574, 42735.7863210682, 42842.78578607087, 31987.200319992186, 32088.197795055312, 32441.0, 32542.34915301728, 33645.66354336425, 36404.81797590995, 36603.45094823633, 36605.0, 36682.44976325304, 37209.813950930074, 35541.82229088838, 35690.64309356977, 35691.46462803008, 36928.81535592305, 37820.62179378171, 31678.208044799063, 32916.177095571846, 33791.4931276036, 33969.830150849084, 34245.14387140242, 25566.744332556435, 25786.87106564455, 25793.742062579135, 25879.61180582255, 26463.33841653974], "value": 16339.897666444205, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.2408297041261, 351.8469465782383, 360.1338780233538, 367.9889603311902, 380.8686003328851, 12796.616101516958, 16214.18929053547, 16536.586585335357, 16875.831241687578, 18024.188911498983, 16459.177041147956, 17570.736438953427, 17673.204705788237, 17773.55566110848, 17826.910865445654, 5044.092063428581, 5070.771815268304, 5207.817726379578, 5213.947860521384, 5217.7130257836, 3815.2178803345246, 5052.040112378655, 5139.974300128512, 5766.192733017381, 6034.155218269446, 6095.603785753934, 6114.205153330061, 6139.324674285825, 6149.600275982069, 6153.0155175171985, 5921.763129474816, 6095.725692343855, 6106.847328816771, 6155.50755939526, 6158.969205153946, 5198.792048318062, 5378.435264297232, 5382.865428364322, 5394.487523685283, 5396.244525766396, 20490.692639610417, 20755.481112972146, 20766.792332076788, 20797.792022079735, 20800.5839883203, 1966.783653798081, 1997.7303064086452, 2054.98972505138, 2136.7863213678665, 2405.579023670868, 13048.934755326163, 13541.59375218745, 13625.114367566077, 13734.587962361145, 14230.217338046403, 1754.8508376787988, 1760.6390689908537, 1768.2484943898792, 1808.5297822566097, 1815.546113471632, 66438.99999999952, 66760.66619666871, 66823.3317666822, 67635.0, 68574.0, 8504.31965442763, 8578.78553036179, 8814.427062240933, 9563.0, 9694.418334899929, 12531.373431328397, 12641.43113559892, 12672.87327126717, 12683.936580317039, 12687.936560317139, 6697.799066028027, 6754.189497260262, 6825.1127353443735, 6829.931700683029, 6942.271061538466, 6313.463355614824, 6414.775482858077, 6505.577137486048, 6584.8024559263295, 6600.306967768315, 5915.3788852170765, 6098.603590766675, 6103.23709536308, 6143.0, 6157.507399408037, 1700.5748562859285, 1725.6462425202803, 1756.394044054799, 1765.3027054313475, 1790.8567314615061, 679.4089142446104, 825.3933358981144, 872.1409411729442, 892.3262936482914, 957.7749228931195, 17255.137243138044, 17283.987613625162, 18261.63476730458, 18311.90844045771, 18501.259949601867, 829.340674164041, 857.4812238595684, 891.3136884598953, 895.83427065993, 957.167264479893, 9981.850272245922, 10356.326838755607, 10547.894521054845, 10589.470526473646, 10660.307080039773, 376661.0, 377625.4543454667, 385464.0, 388550.0, 389205.0, 66525.6673716638, 70275.64862175657, 70298.64850675817, 70450.0, 71305.93041104388, 886.8403687336341, 908.336914052732, 909.3043821476575, 911.1753862754218, 913.881195444588, 15592.610184745472, 15626.374945002528, 15982.60043498922, 16147.75778363349, 16179.0, 33980.49029264513, 35304.823475882455, 36727.816360919096, 36759.44860827036, 36891.26217475689, 45380.77309613563, 45761.542384575725, 45823.0, 47118.76440617775, 47768.41736869818, 7185.53294035886, 7498.58757768328, 7614.657340419583, 7625.23747625233, 7793.493422927491, 11880.762384752084, 12247.387630618612, 12327.075469339656, 12336.938315308365, 12795.168314059556, 10352.741181470523, 10385.273030887765, 10529.947350263199, 10635.840462392916, 10847.240693151403, 9860.0, 10209.846852297072, 10383.117435017806, 10534.36793792375, 10551.314164579278, 10244.487775611338, 10355.43045132525, 10589.523471443958, 10807.945960270148, 11219.55121795119, 6448.613083215022, 7245.38414234796, 7263.963680181565, 7366.116066072105, 7436.367908727819, 8257.339412847077, 10137.442440665835, 10157.695269141937, 10451.111655509367, 10690.144788416752], "value": 8334.614651297277, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.1782212850158, 1409.2319685771254, 1474.144995902376, 1628.5358672778261, 1702.029842989495, 7268.382187514061, 7870.567118808463, 7871.763847084582, 7873.409494287937, 7875.173106823787, 6116.204893363856, 7582.128055273645, 7746.302832745052, 7816.609169541514, 7818.14000459949, 2181.0621432783937, 2203.856749311298, 2204.173434961889, 2207.348832094528, 2210.0, 2124.224657999827, 2155.2025750472267, 2182.618041842671, 2182.770809065049, 2184.421128400975, 1763.8588912886937, 2091.16353458617, 2127.606392817334, 2128.095559387254, 2140.5825863956534, 2137.561799831031, 2167.9132834686593, 2170.4248374180856, 2186.5080356919725, 2190.726159230096, 2158.093600687715, 2165.5127596290868, 2166.39340984523, 2167.8482506224573, 2168.0, 7836.921630783676, 7840.176781437924, 7897.921020789776, 7900.249476299742, 7901.644426000844, 4522.706024108423, 4567.771611419415, 4643.141018911512, 4650.720956742606, 4658.091672123937, 2733.3099969925734, 2908.185708001763, 2916.3584011517432, 2923.2545700846363, 2925.0786002409172, 863.2058506174335, 921.6129225725211, 924.2652091587204, 929.8140371925642, 961.2406199102701, 10504.0, 10508.211884108723, 10510.211734119972, 10516.1061309788, 10531.94734026325, 3901.297766402075, 4135.400366946776, 4197.706160568731, 4248.936265956013, 4252.978735106367, 9028.052054534312, 9047.276217902552, 9052.321075919332, 9053.59258833354, 9063.954680226556, 4648.279516674921, 4664.323673067386, 4664.953350466452, 4666.626669866402, 4675.041616468616, 4186.5604111568455, 4235.724677895927, 4236.110416812442, 4239.427677263591, 4240.978795106066, 3591.7665351752053, 3609.061643972586, 3690.833912473945, 3715.8142092895246, 3739.270842185748, 1095.6274866545414, 1107.6510899066768, 1128.8758236593967, 1133.886611338876, 1136.181948996721, 568.8325938188776, 721.9278072192739, 805.7582725182422, 830.3689196210923, 849.4054162086564, 5028.0, 5203.427622961472, 5428.565714742811, 5433.809816656398, 5522.502974732293, 832.9708460203864, 861.5390716395981, 868.9174528419917, 878.7012415778665, 889.3818795936762, 3274.05052534767, 3499.3876071687355, 3600.5139306193328, 3684.613115622821, 3723.7579557329227, 9269.72190834276, 9735.513224338754, 9740.902590974141, 9754.463504507321, 9776.755581110529, 9656.0, 9656.179224765832, 9664.03359664028, 9693.273004524688, 9701.854472182922, 7275.163356213997, 7423.925760742323, 7440.069991251094, 7440.85118297631, 7449.329560339487, 5126.53861152498, 5214.426413094632, 5239.973800130975, 5311.548518375981, 5512.035393806148, 8743.693970710994, 8755.343349248704, 8925.419847709878, 8928.508932008803, 9007.414518056305, 8955.820883582423, 9029.683961061332, 9147.954260228656, 9202.0, 9203.493807840632, 6262.34245404226, 6453.290138084901, 6464.967675161783, 6489.610623362424, 6489.7404103835315, 7508.361789247975, 7557.470977031555, 7565.621718914143, 7570.242975702386, 7572.4699271050495, 6720.1599800025, 6741.764038258833, 6758.5269031167345, 6822.965885170543, 6941.34057264559, 6690.19717633887, 6769.93230067693, 6770.44279815655, 6781.491388145811, 6799.354061364163, 5852.4147585241135, 6008.218931538872, 6110.175126357884, 6120.326764056039, 6158.107074474266, 4516.480604730498, 4789.114013907439, 4851.078295123915, 4861.611071114373, 4879.463259041431, 4696.671233013656, 4856.223004319292, 4927.482614325444, 4957.529034741694, 4991.425986011655], "value": 4124.424845550408, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.46256724521663, 295.82694123937495, 296.3983114278016, 296.6425457323924, 308.3863112406312, 4668.159731248374, 4816.8554943351755, 4859.927101093486, 4944.283078953554, 4946.455889852118, 4671.813127474906, 4794.0, 4818.204996175636, 4824.710517368961, 4832.308691957813, 3786.3563194256903, 3786.8295926683227, 3787.8295476703615, 3794.260119276742, 3802.4866643003256, 3591.299696559172, 3633.291508155911, 3815.637514436124, 3819.427085937104, 3823.4456003879422, 3783.9810800945957, 3792.0709426190483, 3797.7341586088983, 3799.905002374935, 3809.095452273863, 3763.473113764075, 3778.6599206071446, 3780.3951367781297, 3780.981095094507, 3809.2381523695235, 3327.916802079943, 3346.7489938254485, 3352.4636058230567, 3355.4295769719206, 3361.8319084045943, 4611.331356953223, 4721.394725793212, 4737.905241895177, 4748.715077095385, 4763.49983251757, 4307.0, 4322.708964248953, 4325.589069038437, 4326.935095973563, 4332.891677708051, 5043.293938848564, 5043.319151914481, 5046.6719663221775, 5050.545450909436, 5053.8231161909525, 3384.6953774160443, 3538.43385058392, 3649.94525082124, 3663.56037275526, 3678.2459595782802, 5281.419043905167, 5317.813876514342, 5318.361796584435, 5320.201969704536, 5338.332708411449, 3611.4831770656388, 3633.9636603634153, 3637.5453068366455, 3652.433872749728, 3653.94519082214, 3689.8339574719203, 3691.9446208306895, 3694.131879008431, 3700.870469533553, 3703.5370578677666, 4135.627793498599, 4203.747775133441, 4208.9789551052045, 4240.724352917051, 4241.915161696748, 5061.620378471555, 5075.416327122428, 5077.746112694351, 5081.7967281309075, 5093.930274642366, 1199.6204364980342, 1227.4537830665447, 1234.882686144815, 1276.9170003949714, 1295.1775622479793, 821.9219174178444, 836.9747059851754, 852.4714676900361, 879.6965047058754, 880.7621942075732, 4440.71135376206, 4449.755263460541, 4467.922416076475, 4476.597106260452, 4481.619062379734, 5222.6083043771105, 5224.947750522522, 5225.216217567319, 5240.873212259383, 5246.580273578104, 4300.569943005675, 4521.79651915658, 4536.659750518725, 4539.978504836451, 4546.40896683429, 2779.4580056889117, 2812.4093940272774, 2819.3929460207487, 2824.56219286011, 2841.559558268472, 4799.28010798383, 4975.328330675385, 4984.352034235526, 4998.750062496933, 5018.749062546858, 5389.407165211824, 5403.567714582824, 5421.430749771217, 5423.864403389947, 5445.618806683494, 4955.207166853358, 4960.553550180501, 4973.577245934064, 4974.602031837444, 5000.67495612784, 5116.437191908813, 5224.895502089861, 5231.29377534028, 5286.7356632169, 5311.097113490793, 5135.35808023997, 5142.405854185215, 5175.534201921845, 5182.585393168612, 5185.0, 4895.681780684244, 4921.474342953553, 4926.605871530197, 4943.530364615356, 4955.6531042826655, 3509.4034014218155, 3716.312482190803, 3730.0861288984374, 3741.812909354467, 3743.906402339854, 5237.4598319814095, 5252.660571554229, 5259.7370131494035, 5262.131748261647, 5264.210368444611, 5110.335656364798, 5122.308488354024, 5122.564582010571, 5124.923126153036, 5126.666766660155, 4892.672982372022, 5032.6225533084435, 5075.949240507695, 5120.897582048413, 5127.897442051063, 4797.496262892346, 4941.777620007037, 4968.205087186033, 5003.774830132726, 5017.1721665924715, 5037.319961805109, 5038.823641172541, 5067.7212753297445, 5068.26510156018, 5140.074786538459, 5045.520675535819, 5047.924281135713, 5050.419201791694, 5050.72221027847, 5057.342545469212], "value": 3824.1233018061307, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [582.6940856050572, 648.3484098481026, 685.6948657847257, 704.5209257704762, 732.8424388756416, 12205.694857628552, 12829.48682052718, 12956.611301660954, 13136.592580748329, 13427.0, 13542.593722188325, 13616.931915340434, 13856.307184640778, 13861.584152475441, 13881.514147004831, 639.3127388057833, 765.6516285090285, 790.952542847431, 796.74902405742, 797.2107613462688, 773.7678696391089, 795.5544894858898, 797.8563858505466, 804.5293503300576, 805.3919290935365, 832.4880198677799, 835.3317346123108, 835.8119423129777, 835.8829763833069, 836.067784420373, 669.5012215899186, 831.9459235149726, 832.2967092806568, 832.8500869843426, 832.8584140696096, 594.678873408359, 603.3393434189555, 605.7758629307141, 609.2080295615682, 609.2475792396386, 11764.882351176464, 11787.115966302475, 11803.055755539537, 11815.527378904833, 11823.763524729457, 10144.695659130239, 10289.434081125535, 10527.473626318653, 10928.781424371467, 11141.275817071864, 10400.583976640924, 10776.137908967265, 11025.558977640885, 11039.72400689981, 11396.0, 3942.7943230100236, 4036.535798383165, 4064.8753712582998, 4089.284375234322, 4168.95776055986, 63476.36523634797, 64443.355566444676, 64661.67669161624, 65697.0, 65888.3411165887, 9388.90611093894, 9657.46883921391, 9725.416475011521, 9836.901630983743, 9847.704568862946, 18832.05839708009, 18833.0, 18875.528111797314, 19022.334218302294, 19143.712844307345, 6577.440917521969, 6636.402723754885, 6705.061291419205, 6767.492438067165, 6962.860742785116, 3436.347094052122, 3515.2442224921765, 3538.221591249921, 3581.8925432237074, 3614.313280476701, 2640.683118025849, 2726.8091233613854, 2736.616873637692, 2739.02764518597, 2740.8081434299806, 480.1932752974966, 480.68274938540395, 481.93252944587084, 491.5723320710933, 494.4733858440749, 530.2708775433779, 542.5442628192337, 557.7211394302849, 562.5836880708247, 567.3872218004545, 7636.618169091523, 7733.131159969647, 7737.187595302525, 7859.331956783737, 7935.365170786324, 392.40942381715337, 431.9848805291862, 455.39432554702165, 485.4247716456011, 526.6366207316939, 3727.38498147808, 3810.1046254130256, 3975.8012099394914, 4005.9799701001307, 4295.183915056129, 94753.5262323684, 100087.49956250317, 101000.0, 101351.49324253331, 106357.0, 58505.70747146237, 85801.63279485794, 88981.0, 90094.54952725195, 90941.54529227312, 63973.0, 79678.0, 79843.60078199572, 79952.60023699961, 80465.0, 7754.495957762728, 7996.880046799186, 8076.676932922617, 8183.468074575134, 8216.26053655174, 37316.81341593383, 43531.99738857837, 43930.34104488499, 44700.32949505825, 44973.55026449694, 37704.81147594354, 38545.0, 59634.0, 66569.0, 67289.32710673027, 10594.364338139736, 10627.468626568796, 10662.89337106619, 10676.73308167271, 10696.465176741287, 12842.357882106044, 12913.741725165632, 12924.224546527239, 13493.662658433617, 14094.154350738989, 11437.150538438927, 11632.476538555617, 11691.407358012595, 11746.29522228669, 12177.634670959886, 10624.628138015132, 11221.66335009951, 11284.492197850952, 11325.603603873826, 11348.489317980546, 11479.4260286987, 12009.579664711693, 12130.818037729265, 12135.939320303341, 12278.201916875374, 10077.344972576759, 10137.746556336151, 10180.609352507356, 10345.172386209235, 10416.687499375032, 9939.254555908192, 10022.3485473444, 10088.899111008795, 10114.241431892784, 10722.356658600509], "value": 6438.976318510963, "unit": "score"}], "name": "dromaeo_css", "value": 6343.575564455328, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 112.0, 116.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 128.0, 186.0, 128.0, 129.0, 146.0, 126.0, 173.0, 122.0], "value": 141.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 162.0, 161.0, 161.0, 161.0, 161.0, 161.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 75.0, 108.0, 77.0, 77.0, 76.0, 74.0, 110.0, 77.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 138.0, 85.0, 85.0, 85.0, 118.0, 85.0, 85.0, 129.0], "value": 98.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 147.0, 147.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 99.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 63.0, 62.0, 64.0, 65.0, 64.0, 63.0, 62.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 64.0, 65.0, 64.0, 65.0, 65.0, 66.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 82.0, 79.0, 79.0, 80.0, 80.0, 89.0, 85.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 134.0, 133.0, 131.0, 131.0, 131.0, 141.0, 150.0, 133.0], "value": 134.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 172.0, 171.0, 168.0, 165.0, 169.0, 167.0, 170.0, 165.0, 168.0], "value": 168.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 72.0, 71.0, 67.0, 67.0, 66.0, 67.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1533.7, "alertThreshold": 2.0}]} 16:50:00 INFO - Return code: 0 16:50:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:50:00 INFO - # TBPL SUCCESS # 16:50:00 INFO - Running post-action listener: _resource_record_post_action 16:50:00 INFO - [mozharness: 2016-08-07 23:50:00.353019Z] Finished run-tests step (success) 16:50:00 INFO - Running post-run listener: _resource_record_post_run 16:50:00 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 12288; Write bytes: 540438528; Read time: 284; Write time: 505468 16:50:00 INFO - TinderboxPrint: CPU usage
13.0% 16:50:00 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 284 16:50:00 INFO - TinderboxPrint: I/O write bytes / time
540,438,528 / 505,468 16:50:00 INFO - TinderboxPrint: CPU idle
8,333.1 (87.0%) 16:50:00 INFO - TinderboxPrint: CPU user
1,199.3 (12.5%) 16:50:00 INFO - TinderboxPrint: Swap in / out
0 / 0 16:50:00 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 253595648; Read time: 84; Write time: 281952 16:50:00 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286568448; Read time: 192; Write time: 223492 16:50:00 INFO - Running post-run listener: _upload_blobber_files 16:50:00 INFO - Blob upload gear active. 16:50:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:50:00 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 16:50:00 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:50:00 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:50:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:50:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:09 INFO - (blobuploader) - INFO - Done attempting. 16:50:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:50:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:50:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:10 INFO - (blobuploader) - INFO - Done attempting. 16:50:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:50:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:50:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:50:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:50:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:50:11 INFO - (blobuploader) - INFO - Done attempting. 16:50:11 INFO - (blobuploader) - INFO - Iteration through files over. 16:50:11 INFO - Return code: 0 16:50:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:50:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:50:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c01f382e9ec0db06aec77d41bd2236fe8e6264117404acf4977946329215715e58645a7aa193e02f509ae8105f9ec4a8cc549ad191106038502c8fd8fb7010", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f63749b1f611dd8073870304a079d9df11bddb4e4dfdda42c167966da74608422d3431f4719b929df53763a7f7fb29063e1a0057768a56e89c8f6949f6b63ad7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a7621faba12d6b58f5eed80369a00b9ad7d3925b08c700ae645cd745bfe2b341e861fc79166755af4a6e210918584ed4dad8287f3ef0c33586732b48c88d735"} 16:50:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:50:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:50:11 INFO - Contents: 16:50:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c01f382e9ec0db06aec77d41bd2236fe8e6264117404acf4977946329215715e58645a7aa193e02f509ae8105f9ec4a8cc549ad191106038502c8fd8fb7010", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f63749b1f611dd8073870304a079d9df11bddb4e4dfdda42c167966da74608422d3431f4719b929df53763a7f7fb29063e1a0057768a56e89c8f6949f6b63ad7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a7621faba12d6b58f5eed80369a00b9ad7d3925b08c700ae645cd745bfe2b341e861fc79166755af4a6e210918584ed4dad8287f3ef0c33586732b48c88d735"} 16:50:11 INFO - Running post-run listener: copy_logs_to_upload_dir 16:50:11 INFO - Copying logs to upload dir... 16:50:11 INFO - mkdir: /builds/slave/test/build/upload/logs 16:50:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.134743 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-07 16:50:10.407642) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 16:50:10.411652) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c01f382e9ec0db06aec77d41bd2236fe8e6264117404acf4977946329215715e58645a7aa193e02f509ae8105f9ec4a8cc549ad191106038502c8fd8fb7010", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f63749b1f611dd8073870304a079d9df11bddb4e4dfdda42c167966da74608422d3431f4719b929df53763a7f7fb29063e1a0057768a56e89c8f6949f6b63ad7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a7621faba12d6b58f5eed80369a00b9ad7d3925b08c700ae645cd745bfe2b341e861fc79166755af4a6e210918584ed4dad8287f3ef0c33586732b48c88d735"} build_url:https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011249 build_url: 'https://queue.taskcluster.net/v1/task/RU7_tVGVQ6eQSqz6WojFgw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/56c01f382e9ec0db06aec77d41bd2236fe8e6264117404acf4977946329215715e58645a7aa193e02f509ae8105f9ec4a8cc549ad191106038502c8fd8fb7010", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f63749b1f611dd8073870304a079d9df11bddb4e4dfdda42c167966da74608422d3431f4719b929df53763a7f7fb29063e1a0057768a56e89c8f6949f6b63ad7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3a7621faba12d6b58f5eed80369a00b9ad7d3925b08c700ae645cd745bfe2b341e861fc79166755af4a6e210918584ed4dad8287f3ef0c33586732b48c88d735"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-07 16:50:10.437217) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:50:10.437615) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470605486.220679-1471596569 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004464 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-07 16:50:10.523013) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 16:50:10.523559) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-07 16:50:10.523994) ========= ========= Total master_lag: 0.20 =========