builder: autoland_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-006 starttime: 1470673731.1 results: success (0) buildid: 20160808080652 builduid: 7d9fc7d00439414b843dec26233f67f3 revision: d40036e732c863c786f58a7b67c94ebb66c667e0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.095664) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.096035) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.096315) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.139801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.140071) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-08 09:28:51-- 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.6M=0s 2016-08-08 09:28:51 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114411 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.268103) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.268390) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017591 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.351567) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:51.351861) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d40036e732c863c786f58a7b67c94ebb66c667e0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d40036e732c863c786f58a7b67c94ebb66c667e0 --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=e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-08 09:28:51,529 truncating revision to first 12 chars 2016-08-08 09:28:51,529 Setting DEBUG logging. 2016-08-08 09:28:51,530 attempt 1/10 2016-08-08 09:28:51,530 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d40036e732c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 09:28:51,841 unpacking tar archive at: autoland-d40036e732c8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685016 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:52.060123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:52.060399) ========= script_repo_revision: d40036e732c863c786f58a7b67c94ebb66c667e0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:52.060737) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:52.060991) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 09:28:52.077635) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-08 09:28:52.077883) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-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=e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:28:52 INFO - MultiFileLogger online at 20160808 09:28:52 in /builds/slave/test 09:28:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 09:28:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:28:52 INFO - {'append_to_log': False, 09:28:52 INFO - 'base_work_dir': '/builds/slave/test', 09:28:52 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:28:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:28:52 INFO - 'branch': 'Autoland-Non-PGO', 09:28:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:28:52 INFO - 'config_files': ('talos/linux_config.py',), 09:28:52 INFO - 'default_actions': ('clobber', 09:28:52 INFO - 'read-buildbot-config', 09:28:52 INFO - 'download-and-extract', 09:28:52 INFO - 'populate-webroot', 09:28:52 INFO - 'create-virtualenv', 09:28:52 INFO - 'install', 09:28:52 INFO - 'run-tests'), 09:28:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:28:52 INFO - 'download_minidump_stackwalk': True, 09:28:52 INFO - 'download_symbols': 'ondemand', 09:28:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:28:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:28:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:28:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:28:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:28:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:28:52 INFO - 'installer_path': 'installer.exe', 09:28:52 INFO - 'log_level': 'info', 09:28:52 INFO - 'log_name': 'talos', 09:28:52 INFO - 'log_to_console': True, 09:28:52 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:28:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:28:52 INFO - 'opt_config_files': (), 09:28:52 INFO - 'pip_index': False, 09:28:52 INFO - 'sps_profile': False, 09:28:52 INFO - 'sps_profile_interval': 0, 09:28:52 INFO - 'suite': 'dromaeojs-e10s', 09:28:52 INFO - 'system_bits': '32', 09:28:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:28:52 INFO - 'title': 'talos-linux64-ix-006', 09:28:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:28:52 INFO - 'use_talos_json': True, 09:28:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:28:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:28:52 INFO - 'work_dir': 'build'} 09:28:52 INFO - [mozharness: 2016-08-08 16:28:52.357746Z] Running clobber step. 09:28:52 INFO - Running pre-action listener: _resource_record_pre_action 09:28:52 INFO - Running main action method: clobber 09:28:52 INFO - rmtree: /builds/slave/test/build 09:28:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:28:52 INFO - Running post-action listener: _resource_record_post_action 09:28:52 INFO - [mozharness: 2016-08-08 16:28:52.824749Z] Finished clobber step (success) 09:28:52 INFO - [mozharness: 2016-08-08 16:28:52.824869Z] Running read-buildbot-config step. 09:28:52 INFO - Running pre-action listener: _resource_record_pre_action 09:28:52 INFO - Running main action method: read_buildbot_config 09:28:52 INFO - Using buildbot properties: 09:28:52 INFO - { 09:28:52 INFO - "project": "", 09:28:52 INFO - "product": "firefox", 09:28:52 INFO - "script_repo_revision": "production", 09:28:52 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 09:28:52 INFO - "repository": "", 09:28:52 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs-e10s", 09:28:52 INFO - "buildid": "20160808080652", 09:28:52 INFO - "pgo_build": "False", 09:28:52 INFO - "basedir": "/builds/slave/test", 09:28:52 INFO - "buildnumber": 246, 09:28:52 INFO - "slavename": "talos-linux64-ix-006", 09:28:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:28:52 INFO - "platform": "ubuntu64_hw", 09:28:52 INFO - "scheduler": "tests-autoland-linux64-talos", 09:28:52 INFO - "branch": "autoland", 09:28:52 INFO - "repo_path": "integration/autoland", 09:28:52 INFO - "revision": "d40036e732c863c786f58a7b67c94ebb66c667e0", 09:28:52 INFO - "stage_platform": "linux64", 09:28:52 INFO - "builduid": "7d9fc7d00439414b843dec26233f67f3", 09:28:52 INFO - "slavebuilddir": "test" 09:28:52 INFO - } 09:28:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:28:52 INFO - Running post-action listener: _resource_record_post_action 09:28:52 INFO - [mozharness: 2016-08-08 16:28:52.827323Z] Finished read-buildbot-config step (success) 09:28:52 INFO - [mozharness: 2016-08-08 16:28:52.827413Z] Running download-and-extract step. 09:28:52 INFO - Running pre-action listener: _resource_record_pre_action 09:28:52 INFO - Running main action method: download_and_extract 09:28:52 INFO - mkdir: /builds/slave/test/build/tests 09:28:52 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')]} 09:28:52 INFO - trying https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:28:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:53 INFO - Downloaded 1361 bytes. 09:28:53 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:28:53 INFO - Using the following test package requirements: 09:28:53 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:28:53 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:28:53 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:28:53 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'jsshell-linux-x86_64.zip'], 09:28:53 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:28:53 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:28:53 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:28:53 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:28:53 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:28:53 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:28:53 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:28:53 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:28:53 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')]} 09:28:53 INFO - trying https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:28:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:54 INFO - Downloaded 20257866 bytes. 09:28:54 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'] 09:28:54 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 09:28:55 INFO - Return code: 0 09:28:55 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 09:28: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')]} 09:28:55 INFO - trying https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:28:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:56 INFO - Downloaded 20257866 bytes. 09:28:56 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'] 09:28:56 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 09:28:57 INFO - Return code: 0 09:28:57 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')]} 09:28:57 INFO - trying https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:28:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:59 INFO - Downloaded 14413723 bytes. 09:28:59 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'] 09:28:59 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 09:28:59 INFO - Return code: 0 09:28: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')]} 09:28:59 INFO - trying https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:28:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:28:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/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 09:29:00 INFO - Downloaded 56133376 bytes. 09:29:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:29:00 INFO - mkdir: /builds/slave/test/properties 09:29:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:29:00 INFO - Writing to file /builds/slave/test/properties/build_url 09:29:00 INFO - Contents: 09:29:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:29:01 INFO - Running post-action listener: _resource_record_post_action 09:29:01 INFO - Running post-action listener: set_extra_try_arguments 09:29:01 INFO - [mozharness: 2016-08-08 16:29:01.462388Z] Finished download-and-extract step (success) 09:29:01 INFO - [mozharness: 2016-08-08 16:29:01.462493Z] Running populate-webroot step. 09:29:01 INFO - Running pre-action listener: _resource_record_pre_action 09:29:01 INFO - Running main action method: populate_webroot 09:29:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:29:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:29:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:29:01 INFO - '--tppagecycles', 09:29:01 INFO - '7'], 09:29:01 INFO - 'tests': ['tsvgm']}}, 09:29:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:29:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:29:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:29:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:29:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:29:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['damp', 'tps']}, 09:29:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'tests': ['damp', 'tps']}, 09:29:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['dromaeo_dom']}, 09:29:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:29:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['basic_compositor_video']}, 09:29:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:29:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['a11yr', 09:29:01 INFO - 'ts_paint', 09:29:01 INFO - 'tpaint', 09:29:01 INFO - 'sessionrestore', 09:29:01 INFO - 'sessionrestore_no_auto_restore', 09:29:01 INFO - 'tabpaint']}, 09:29:01 INFO - 'other-e10s': {'tests': ['a11yr', 09:29:01 INFO - 'ts_paint', 09:29:01 INFO - 'tpaint', 09:29:01 INFO - 'sessionrestore', 09:29:01 INFO - 'sessionrestore_no_auto_restore', 09:29:01 INFO - 'tabpaint']}, 09:29:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:29:01 INFO - 'ts_paint', 09:29:01 INFO - 'tpaint', 09:29:01 INFO - 'sessionrestore', 09:29:01 INFO - 'sessionrestore_no_auto_restore', 09:29:01 INFO - 'tabpaint']}, 09:29:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:29:01 INFO - 'ts_paint', 09:29:01 INFO - 'tpaint', 09:29:01 INFO - 'sessionrestore', 09:29:01 INFO - 'sessionrestore_no_auto_restore', 09:29:01 INFO - 'tabpaint']}, 09:29:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['a11yr', 09:29:01 INFO - 'ts_paint', 09:29:01 INFO - 'tpaint', 09:29:01 INFO - 'sessionrestore', 09:29:01 INFO - 'sessionrestore_no_auto_restore', 09:29:01 INFO - 'tabpaint']}, 09:29:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['a11yr', 09:29:01 INFO - 'ts_paint', 09:29:01 INFO - 'tpaint', 09:29:01 INFO - 'sessionrestore', 09:29:01 INFO - 'sessionrestore_no_auto_restore', 09:29:01 INFO - 'tabpaint']}, 09:29:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['tsvgx', 09:29:01 INFO - 'tsvgr_opacity', 09:29:01 INFO - 'tart', 09:29:01 INFO - 'tscrollx', 09:29:01 INFO - 'cart']}, 09:29:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:29:01 INFO - 'tsvgr_opacity', 09:29:01 INFO - 'tart', 09:29:01 INFO - 'tscrollx', 09:29:01 INFO - 'cart']}, 09:29:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'talos_options': ['--disable-e10s'], 09:29:01 INFO - 'tests': ['tp5o']}, 09:29:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'tests': ['tp5o']}, 09:29:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'talos_options': ['--disable-e10s', 09:29:01 INFO - '--xperf_path', 09:29:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:01 INFO - 'tests': ['tp5n']}, 09:29:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:29:01 INFO - 'talos_options': ['--xperf_path', 09:29:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:29:01 INFO - 'tests': ['tp5n']}}, 09:29:01 INFO - 'talos.zip': {'path': '', 09:29:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:29:01 INFO - Running post-action listener: _resource_record_post_action 09:29:01 INFO - [mozharness: 2016-08-08 16:29:01.483432Z] Finished populate-webroot step (success) 09:29:01 INFO - [mozharness: 2016-08-08 16:29:01.483521Z] Running create-virtualenv step. 09:29:01 INFO - Running pre-action listener: _resource_record_pre_action 09:29:01 INFO - Running main action method: create_virtualenv 09:29:01 INFO - Creating virtualenv /builds/slave/test/build/venv 09:29:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:29:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:29:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:29:01 INFO - Using real prefix '/usr' 09:29:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:29:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:29:04 INFO - Installing pip.................done. 09:29:04 INFO - Return code: 0 09:29:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:04 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:29:04 INFO - Using env: {'DISPLAY': ':0', 09:29:04 INFO - 'HOME': '/home/cltbld', 09:29:04 INFO - 'LANG': 'en_US.UTF-8', 09:29:04 INFO - 'LANGUAGE': 'en_US:en', 09:29:04 INFO - 'LOGNAME': 'cltbld', 09:29:04 INFO - 'MAIL': '/var/mail/cltbld', 09:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:04 INFO - 'MOZ_NO_REMOTE': '1', 09:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:04 INFO - 'NO_EM_RESTART': '1', 09:29:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:04 INFO - 'PWD': '/builds/slave/test', 09:29:04 INFO - 'SHELL': '/bin/bash', 09:29:04 INFO - 'SHLVL': '1', 09:29:04 INFO - 'TERM': 'linux', 09:29:04 INFO - 'TMOUT': '86400', 09:29:04 INFO - 'USER': 'cltbld', 09:29:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:04 INFO - '_': '/tools/buildbot/bin/python'} 09:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:29:04 INFO - Cleaning up... 09:29:04 INFO - Return code: 0 09:29:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:04 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:29:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:29:04 INFO - Using env: {'DISPLAY': ':0', 09:29:04 INFO - 'HOME': '/home/cltbld', 09:29:04 INFO - 'LANG': 'en_US.UTF-8', 09:29:04 INFO - 'LANGUAGE': 'en_US:en', 09:29:04 INFO - 'LOGNAME': 'cltbld', 09:29:04 INFO - 'MAIL': '/var/mail/cltbld', 09:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:04 INFO - 'MOZ_NO_REMOTE': '1', 09:29:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:04 INFO - 'NO_EM_RESTART': '1', 09:29:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:04 INFO - 'PWD': '/builds/slave/test', 09:29:04 INFO - 'SHELL': '/bin/bash', 09:29:04 INFO - 'SHLVL': '1', 09:29:04 INFO - 'TERM': 'linux', 09:29:04 INFO - 'TMOUT': '86400', 09:29:04 INFO - 'USER': 'cltbld', 09:29:04 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:04 INFO - '_': '/tools/buildbot/bin/python'} 09:29:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:04 INFO - Downloading/unpacking psutil>=3.1.1 09:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:29:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:06 INFO - Installing collected packages: psutil 09:29:06 INFO - Running setup.py install for psutil 09:29:06 INFO - building 'psutil._psutil_linux' extension 09:29:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:29:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:29:06 INFO - building 'psutil._psutil_posix' extension 09:29:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:29:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:29:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:29:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:29:06 INFO - Successfully installed psutil 09:29:06 INFO - Cleaning up... 09:29:07 INFO - Return code: 0 09:29:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:29:07 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')]} 09:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:07 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')]} 09:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:07 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:29:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:29:07 INFO - Using env: {'DISPLAY': ':0', 09:29:07 INFO - 'HOME': '/home/cltbld', 09:29:07 INFO - 'LANG': 'en_US.UTF-8', 09:29:07 INFO - 'LANGUAGE': 'en_US:en', 09:29:07 INFO - 'LOGNAME': 'cltbld', 09:29:07 INFO - 'MAIL': '/var/mail/cltbld', 09:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:07 INFO - 'MOZ_NO_REMOTE': '1', 09:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:07 INFO - 'NO_EM_RESTART': '1', 09:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:07 INFO - 'PWD': '/builds/slave/test', 09:29:07 INFO - 'SHELL': '/bin/bash', 09:29:07 INFO - 'SHLVL': '1', 09:29:07 INFO - 'TERM': 'linux', 09:29:07 INFO - 'TMOUT': '86400', 09:29:07 INFO - 'USER': 'cltbld', 09:29:07 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:07 INFO - '_': '/tools/buildbot/bin/python'} 09:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:29:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:29:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:29:09 INFO - Installing collected packages: mozsystemmonitor 09:29:09 INFO - Running setup.py install for mozsystemmonitor 09:29:09 INFO - Successfully installed mozsystemmonitor 09:29:09 INFO - Cleaning up... 09:29:09 INFO - Return code: 0 09:29:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:09 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:29:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:29:09 INFO - Using env: {'DISPLAY': ':0', 09:29:09 INFO - 'HOME': '/home/cltbld', 09:29:09 INFO - 'LANG': 'en_US.UTF-8', 09:29:09 INFO - 'LANGUAGE': 'en_US:en', 09:29:09 INFO - 'LOGNAME': 'cltbld', 09:29:09 INFO - 'MAIL': '/var/mail/cltbld', 09:29:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:09 INFO - 'MOZ_NO_REMOTE': '1', 09:29:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:09 INFO - 'NO_EM_RESTART': '1', 09:29:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:09 INFO - 'PWD': '/builds/slave/test', 09:29:09 INFO - 'SHELL': '/bin/bash', 09:29:09 INFO - 'SHLVL': '1', 09:29:09 INFO - 'TERM': 'linux', 09:29:09 INFO - 'TMOUT': '86400', 09:29:09 INFO - 'USER': 'cltbld', 09:29:09 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:09 INFO - '_': '/tools/buildbot/bin/python'} 09:29:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:09 INFO - Downloading/unpacking jsonschema==2.5.1 09:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:29:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:11 INFO - Downloading functools32-3.2.3-2.tar.gz 09:29:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:29:11 INFO - warning: no files found matching '*.txt' 09:29:11 INFO - no previously-included directories found matching 'build' 09:29:11 INFO - no previously-included directories found matching 'dist' 09:29:11 INFO - no previously-included directories found matching '.git*' 09:29:11 INFO - Installing collected packages: jsonschema, functools32 09:29:11 INFO - Running setup.py install for functools32 09:29:11 INFO - warning: no files found matching '*.txt' 09:29:11 INFO - no previously-included directories found matching 'build' 09:29:11 INFO - no previously-included directories found matching 'dist' 09:29:11 INFO - no previously-included directories found matching '.git*' 09:29:11 INFO - Successfully installed jsonschema functools32 09:29:11 INFO - Cleaning up... 09:29:11 INFO - Return code: 0 09:29:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:29:11 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')]} 09:29:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:11 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')]} 09:29:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:11 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:11 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 09:29:11 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 09:29:11 INFO - Using env: {'DISPLAY': ':0', 09:29:11 INFO - 'HOME': '/home/cltbld', 09:29:11 INFO - 'LANG': 'en_US.UTF-8', 09:29:11 INFO - 'LANGUAGE': 'en_US:en', 09:29:11 INFO - 'LOGNAME': 'cltbld', 09:29:11 INFO - 'MAIL': '/var/mail/cltbld', 09:29:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:11 INFO - 'MOZ_NO_REMOTE': '1', 09:29:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:11 INFO - 'NO_EM_RESTART': '1', 09:29:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:11 INFO - 'PWD': '/builds/slave/test', 09:29:11 INFO - 'SHELL': '/bin/bash', 09:29:11 INFO - 'SHLVL': '1', 09:29:11 INFO - 'TERM': 'linux', 09:29:11 INFO - 'TMOUT': '86400', 09:29:11 INFO - 'USER': 'cltbld', 09:29:11 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:11 INFO - '_': '/tools/buildbot/bin/python'} 09:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:29:12 INFO - Cleaning up... 09:29:12 INFO - Return code: 0 09:29:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:29:12 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')]} 09:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:12 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')]} 09:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:12 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:29:12 INFO - Using env: {'DISPLAY': ':0', 09:29:12 INFO - 'HOME': '/home/cltbld', 09:29:12 INFO - 'LANG': 'en_US.UTF-8', 09:29:12 INFO - 'LANGUAGE': 'en_US:en', 09:29:12 INFO - 'LOGNAME': 'cltbld', 09:29:12 INFO - 'MAIL': '/var/mail/cltbld', 09:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:12 INFO - 'MOZ_NO_REMOTE': '1', 09:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:12 INFO - 'NO_EM_RESTART': '1', 09:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:12 INFO - 'PWD': '/builds/slave/test', 09:29:12 INFO - 'SHELL': '/bin/bash', 09:29:12 INFO - 'SHLVL': '1', 09:29:12 INFO - 'TERM': 'linux', 09:29:12 INFO - 'TMOUT': '86400', 09:29:12 INFO - 'USER': 'cltbld', 09:29:12 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:12 INFO - '_': '/tools/buildbot/bin/python'} 09:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:12 INFO - Downloading/unpacking blobuploader==1.2.4 09:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:14 INFO - Downloading blobuploader-1.2.4.tar.gz 09:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:29:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:29:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:14 INFO - Downloading docopt-0.6.1.tar.gz 09:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:29:14 INFO - Installing collected packages: blobuploader, requests, docopt 09:29:14 INFO - Running setup.py install for blobuploader 09:29:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:29:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:29:15 INFO - Running setup.py install for requests 09:29:15 INFO - Running setup.py install for docopt 09:29:15 INFO - Successfully installed blobuploader requests docopt 09:29:15 INFO - Cleaning up... 09:29:15 INFO - Return code: 0 09:29:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29: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')]} 09:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29: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')]} 09:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:15 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:29:15 INFO - Using env: {'DISPLAY': ':0', 09:29:15 INFO - 'HOME': '/home/cltbld', 09:29:15 INFO - 'LANG': 'en_US.UTF-8', 09:29:15 INFO - 'LANGUAGE': 'en_US:en', 09:29:15 INFO - 'LOGNAME': 'cltbld', 09:29:15 INFO - 'MAIL': '/var/mail/cltbld', 09:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:15 INFO - 'MOZ_NO_REMOTE': '1', 09:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:15 INFO - 'NO_EM_RESTART': '1', 09:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:15 INFO - 'PWD': '/builds/slave/test', 09:29:15 INFO - 'SHELL': '/bin/bash', 09:29:15 INFO - 'SHLVL': '1', 09:29:15 INFO - 'TERM': 'linux', 09:29:15 INFO - 'TMOUT': '86400', 09:29:15 INFO - 'USER': 'cltbld', 09:29:15 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:15 INFO - '_': '/tools/buildbot/bin/python'} 09:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:15 INFO - Running setup.py (path:/tmp/pip-ZDR6PU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:15 INFO - Running setup.py (path:/tmp/pip-tYThe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:15 INFO - Running setup.py (path:/tmp/pip-vBnbNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:15 INFO - Running setup.py (path:/tmp/pip-APW08Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:16 INFO - Running setup.py (path:/tmp/pip-qrfztR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:16 INFO - Running setup.py (path:/tmp/pip-ZJDw8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:16 INFO - Running setup.py (path:/tmp/pip-cRy6z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:16 INFO - Running setup.py (path:/tmp/pip-Yi6Lik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:16 INFO - Running setup.py (path:/tmp/pip-898DrI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:16 INFO - Running setup.py (path:/tmp/pip-A3YiNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:16 INFO - Running setup.py (path:/tmp/pip-9TqMwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:16 INFO - Running setup.py (path:/tmp/pip-HLNCrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:16 INFO - Running setup.py (path:/tmp/pip-Qcx76E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:16 INFO - Running setup.py (path:/tmp/pip-XPLO1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:16 INFO - Running setup.py (path:/tmp/pip-KAm4XT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:16 INFO - Running setup.py (path:/tmp/pip-KwZhx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:16 INFO - Running setup.py (path:/tmp/pip-ArmpQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:29:16 INFO - Running setup.py install for manifestparser 09:29:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Running setup.py install for mozcrash 09:29:17 INFO - Running setup.py install for mozdebug 09:29:17 INFO - Running setup.py install for mozdevice 09:29:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Running setup.py install for mozfile 09:29:17 INFO - Running setup.py install for mozhttpd 09:29:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Running setup.py install for mozinfo 09:29:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Running setup.py install for mozInstall 09:29:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:29:17 INFO - Running setup.py install for mozleak 09:29:17 INFO - Running setup.py install for mozlog 09:29:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Running setup.py install for moznetwork 09:29:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Running setup.py install for mozprocess 09:29:18 INFO - Running setup.py install for mozprofile 09:29:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Running setup.py install for mozrunner 09:29:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Running setup.py install for mozscreenshot 09:29:18 INFO - Running setup.py install for moztest 09:29:18 INFO - Running setup.py install for mozversion 09:29:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:29:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:29:18 INFO - Cleaning up... 09:29:18 INFO - Return code: 0 09:29:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:18 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:29:18 INFO - Using env: {'DISPLAY': ':0', 09:29:18 INFO - 'HOME': '/home/cltbld', 09:29:18 INFO - 'LANG': 'en_US.UTF-8', 09:29:18 INFO - 'LANGUAGE': 'en_US:en', 09:29:18 INFO - 'LOGNAME': 'cltbld', 09:29:18 INFO - 'MAIL': '/var/mail/cltbld', 09:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:18 INFO - 'MOZ_NO_REMOTE': '1', 09:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:18 INFO - 'NO_EM_RESTART': '1', 09:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:18 INFO - 'PWD': '/builds/slave/test', 09:29:18 INFO - 'SHELL': '/bin/bash', 09:29:18 INFO - 'SHLVL': '1', 09:29:18 INFO - 'TERM': 'linux', 09:29:18 INFO - 'TMOUT': '86400', 09:29:18 INFO - 'USER': 'cltbld', 09:29:18 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:18 INFO - '_': '/tools/buildbot/bin/python'} 09:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:29:19 INFO - Running setup.py (path:/tmp/pip-oOwuwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:29:19 INFO - Running setup.py (path:/tmp/pip-06qBoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:29:19 INFO - Running setup.py (path:/tmp/pip-MOLDnW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:29:19 INFO - Running setup.py (path:/tmp/pip-fqnJ7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:29:19 INFO - Running setup.py (path:/tmp/pip-drfSfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:29:19 INFO - Running setup.py (path:/tmp/pip-PvqR2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:29:19 INFO - Running setup.py (path:/tmp/pip-skOsyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:29:19 INFO - Running setup.py (path:/tmp/pip-dsNWP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:29:19 INFO - Running setup.py (path:/tmp/pip-YoQHM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:29:19 INFO - Running setup.py (path:/tmp/pip-Kn7Fcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:29:19 INFO - Running setup.py (path:/tmp/pip-f3hnD1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:29:19 INFO - Running setup.py (path:/tmp/pip-wd0Nfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:29:19 INFO - Running setup.py (path:/tmp/pip-BCwsRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:29:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:29:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:29:19 INFO - Running setup.py (path:/tmp/pip-BcAlFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:20 INFO - Running setup.py (path:/tmp/pip-p4fVQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:29:20 INFO - Running setup.py (path:/tmp/pip-Y3VaaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:29:20 INFO - Running setup.py (path:/tmp/pip-XdSJmq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:29:22 INFO - Downloading blessings-1.6.tar.gz 09:29:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:29:22 INFO - Installing collected packages: blessings 09:29:22 INFO - Running setup.py install for blessings 09:29:22 INFO - Successfully installed blessings 09:29:22 INFO - Cleaning up... 09:29:22 INFO - Return code: 0 09:29:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:29:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:22 INFO - Reading from file tmpfile_stdout 09:29:22 INFO - Current package versions: 09:29:22 INFO - argparse == 1.2.1 09:29:22 INFO - blessings == 1.6 09:29:22 INFO - blobuploader == 1.2.4 09:29:22 INFO - docopt == 0.6.1 09:29:22 INFO - functools32 == 3.2.3-2 09:29:22 INFO - jsonschema == 2.5.1 09:29:22 INFO - manifestparser == 1.1 09:29:22 INFO - mozInstall == 1.12 09:29:22 INFO - mozcrash == 0.17 09:29:22 INFO - mozdebug == 0.1 09:29:22 INFO - mozdevice == 0.48 09:29:22 INFO - mozfile == 1.2 09:29:22 INFO - mozhttpd == 0.7 09:29:22 INFO - mozinfo == 0.9 09:29:22 INFO - mozleak == 0.1 09:29:22 INFO - mozlog == 3.3 09:29:22 INFO - moznetwork == 0.27 09:29:22 INFO - mozprocess == 0.23 09:29:22 INFO - mozprofile == 0.28 09:29:22 INFO - mozrunner == 6.12 09:29:22 INFO - mozscreenshot == 0.1 09:29:22 INFO - mozsystemmonitor == 0.3 09:29:22 INFO - moztest == 0.7 09:29:22 INFO - mozversion == 1.4 09:29:22 INFO - psutil == 3.1.1 09:29:22 INFO - requests == 1.2.3 09:29:22 INFO - wsgiref == 0.1.2 09:29:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:29:22 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')]} 09:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:22 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')]} 09:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:22 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:29:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:29:22 INFO - Using env: {'DISPLAY': ':0', 09:29:22 INFO - 'HOME': '/home/cltbld', 09:29:22 INFO - 'LANG': 'en_US.UTF-8', 09:29:22 INFO - 'LANGUAGE': 'en_US:en', 09:29:22 INFO - 'LOGNAME': 'cltbld', 09:29:22 INFO - 'MAIL': '/var/mail/cltbld', 09:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:22 INFO - 'MOZ_NO_REMOTE': '1', 09:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:22 INFO - 'NO_EM_RESTART': '1', 09:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:22 INFO - 'PWD': '/builds/slave/test', 09:29:22 INFO - 'SHELL': '/bin/bash', 09:29:22 INFO - 'SHLVL': '1', 09:29:22 INFO - 'TERM': 'linux', 09:29:22 INFO - 'TMOUT': '86400', 09:29:22 INFO - 'USER': 'cltbld', 09:29:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:22 INFO - '_': '/tools/buildbot/bin/python'} 09:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:29:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:29:22 INFO - Cleaning up... 09:29:22 INFO - Return code: 0 09:29:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:29:22 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')]} 09:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:22 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')]} 09:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:29:22 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 0xe16030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf798a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1045400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, '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': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:29:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:29:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:29:22 INFO - Using env: {'DISPLAY': ':0', 09:29:22 INFO - 'HOME': '/home/cltbld', 09:29:22 INFO - 'LANG': 'en_US.UTF-8', 09:29:22 INFO - 'LANGUAGE': 'en_US:en', 09:29:22 INFO - 'LOGNAME': 'cltbld', 09:29:22 INFO - 'MAIL': '/var/mail/cltbld', 09:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:22 INFO - 'MOZ_NO_REMOTE': '1', 09:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:22 INFO - 'NO_EM_RESTART': '1', 09:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:22 INFO - 'PWD': '/builds/slave/test', 09:29:22 INFO - 'SHELL': '/bin/bash', 09:29:22 INFO - 'SHLVL': '1', 09:29:22 INFO - 'TERM': 'linux', 09:29:22 INFO - 'TMOUT': '86400', 09:29:22 INFO - 'USER': 'cltbld', 09:29:22 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:22 INFO - '_': '/tools/buildbot/bin/python'} 09:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:29:23 INFO - Cleaning up... 09:29:23 INFO - Return code: 0 09:29:23 INFO - Running post-action listener: _resource_record_post_action 09:29:23 INFO - Running post-action listener: _start_resource_monitoring 09:29:23 INFO - Starting resource monitoring. 09:29:23 INFO - [mozharness: 2016-08-08 16:29:23.085414Z] Finished create-virtualenv step (success) 09:29:23 INFO - [mozharness: 2016-08-08 16:29:23.085850Z] Running install step. 09:29:23 INFO - Running pre-action listener: _resource_record_pre_action 09:29:23 INFO - Running main action method: install 09:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:29:23 INFO - Reading from file tmpfile_stdout 09:29:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:29:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:29:23 INFO - Reading from file tmpfile_stdout 09:29:23 INFO - Output received: 09:29:23 INFO - Usage: mozinstall [options] installer 09:29:23 INFO - Options: 09:29:23 INFO - -h, --help show this help message and exit 09:29:23 INFO - -d DEST, --destination=DEST 09:29:23 INFO - Directory to install application into. [default: 09:29:23 INFO - "/builds/slave/test"] 09:29:23 INFO - --app=APP Application being installed. [default: firefox] 09:29:23 INFO - mkdir: /builds/slave/test/build/application 09:29:23 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'] 09:29:23 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 09:29:39 INFO - Reading from file tmpfile_stdout 09:29:39 INFO - Output received: 09:29:39 INFO - /builds/slave/test/build/application/firefox/firefox 09:29:39 INFO - Running post-action listener: _resource_record_post_action 09:29:39 INFO - [mozharness: 2016-08-08 16:29:39.919885Z] Finished install step (success) 09:29:39 INFO - [mozharness: 2016-08-08 16:29:39.920134Z] Running run-tests step. 09:29:39 INFO - Running pre-action listener: _resource_record_pre_action 09:29:39 INFO - Running main action method: run_tests 09:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:29:39 INFO - Python 2.7.3 09:29:39 INFO - Return code: 0 09:29:39 INFO - grabbing minidump binary from tooltool 09:29:39 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')]} 09:29:39 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 0x10d4570>, '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 0x10d5470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:29:39 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:29:39 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:29:39 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:29:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:29:39 INFO - Return code: 0 09:29:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:29:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:29:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:29:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:29:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:29:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:29:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-006 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:29:39 INFO - Using env: {'DISPLAY': ':0', 09:29:40 INFO - 'HOME': '/home/cltbld', 09:29:40 INFO - 'LANG': 'en_US.UTF-8', 09:29:40 INFO - 'LANGUAGE': 'en_US:en', 09:29:40 INFO - 'LOGNAME': 'cltbld', 09:29:40 INFO - 'MAIL': '/var/mail/cltbld', 09:29:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:29:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:29:40 INFO - 'MOZ_NO_REMOTE': '1', 09:29:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:29:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:29:40 INFO - 'NO_EM_RESTART': '1', 09:29:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:29:40 INFO - 'PWD': '/builds/slave/test', 09:29:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:29:40 INFO - 'SHELL': '/bin/bash', 09:29:40 INFO - 'SHLVL': '1', 09:29:40 INFO - 'TERM': 'linux', 09:29:40 INFO - 'TMOUT': '86400', 09:29:40 INFO - 'USER': 'cltbld', 09:29:40 INFO - 'XDG_SESSION_COOKIE': 'e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012', 09:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:29:40 INFO - '_': '/tools/buildbot/bin/python'} 09:29:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-006', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:29:40 INFO - mozversion application_buildid: 20160808080652 09:29:40 INFO - mozversion application_changeset: d40036e732c863c786f58a7b67c94ebb66c667e0 09:29:40 INFO - mozversion application_display_name: Nightly 09:29:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:29:40 INFO - mozversion application_name: Firefox 09:29:40 INFO - mozversion application_remotingname: firefox 09:29:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:29:40 INFO - mozversion application_vendor: Mozilla 09:29:40 INFO - mozversion application_version: 51.0a1 09:29:40 INFO - mozversion platform_buildid: 20160808080652 09:29:40 INFO - mozversion platform_changeset: d40036e732c863c786f58a7b67c94ebb66c667e0 09:29:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:29:40 INFO - mozversion platform_version: 51.0a1 09:29:40 INFO - using testdate: 1470673780 09:29:40 INFO - actual date: 1470673780 09:29:40 INFO - starting webserver on 'localhost:41575' 09:29:40 INFO - SUITE-START | Running 2 tests 09:29:40 INFO - TEST-START | dromaeo_css 09:29:40 INFO - operating with platform_type : linux_ 09:29:40 INFO - Initialising browser for dromaeo_css test... 09:29:40 INFO - TEST-INFO | started process 3038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP4xAeF/profile http://localhost:41575/getInfo.html) 09:29:44 INFO - PROCESS | 3038 | __metrics Screen width/height:1600/1200 09:29:44 INFO - PROCESS | 3038 | colorDepth:24 09:29:44 INFO - PROCESS | 3038 | Browser inner width/height: 1024/697 09:29:44 INFO - PROCESS | 3038 | __metrics 09:29:45 ERROR - PROCESS | 3038 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:29:46 INFO - TEST-INFO | 3038: exit 0 09:29:46 INFO - Browser initialized. 09:29:46 INFO - Running cycle 1/1 for dromaeo_css test... 09:29:46 INFO - TEST-INFO | started process 3155 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpP4xAeF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:58 INFO - PROCESS | 3155 | RSS: Main: 165076992 09:29:58 INFO - PROCESS | 3155 | 09:30:47 INFO - PROCESS | 3155 | 1470673847773 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:33:00 INFO - PROCESS | 3155 | Cycle 1(1): loaded http://localhost:41575/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41575/tests/dromaeo/cssquery-ext.html) 09:33:01 INFO - PROCESS | 3155 | RSS: Main: 166977536 09:33:01 INFO - PROCESS | 3155 | 09:36:03 INFO - PROCESS | 3155 | Cycle 1(1): loaded http://localhost:41575/tests/dromaeo/cssquery-ext.html (next: http://localhost:41575/tests/dromaeo/cssquery-jquery.html) 09:36:04 INFO - PROCESS | 3155 | RSS: Main: 166813696 09:36:04 INFO - PROCESS | 3155 | 09:39:06 INFO - PROCESS | 3155 | Cycle 1(1): loaded http://localhost:41575/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41575/tests/dromaeo/cssquery-mootools.html) 09:39:07 INFO - PROCESS | 3155 | RSS: Main: 170098688 09:39:07 INFO - PROCESS | 3155 | 09:42:09 INFO - PROCESS | 3155 | Cycle 1(1): loaded http://localhost:41575/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41575/tests/dromaeo/cssquery-prototype.html) 09:42:09 INFO - PROCESS | 3155 | RSS: Main: 164487168 09:42:09 INFO - PROCESS | 3155 | 09:45:12 INFO - PROCESS | 3155 | Cycle 1(1): loaded http://localhost:41575/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41575/tests/dromaeo/cssquery-yui.html) 09:45:13 INFO - PROCESS | 3155 | RSS: Main: 166289408 09:45:13 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | Cycle 1(1): loaded http://localhost:41575/tests/dromaeo/cssquery-yui.html (next: http://localhost:41575/tests/dromaeo/cssquery-dojo.html) 09:48:16 INFO - PROCESS | 3155 | RSS: Main: 169791488 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | __start_tp_report 09:48:16 INFO - PROCESS | 3155 | _x_x_mozilla_page_load 09:48:16 INFO - PROCESS | 3155 | _x_x_mozilla_page_load_details 09:48:16 INFO - PROCESS | 3155 | |i|pagename|runs| 09:48:16 INFO - PROCESS | 3155 | |0;dojo.html;2666.79999000075;2667.70655227925;2706.336947447875;2709.5800150976593;2711.7017128115904;4716.457607375144;4765.356676848624;4904.411470623521;4939.802407903689;4944.653874228801;4755.405574303212;4811.2302031674935;4827.58965487934;4859.514048595139;4877.658563900528;4411.69118161728;4461.040876211614;4488.416505854234;4496.280595104784;4590.449146102455;4537.614302784267;4546.568076032771;4588.082383523292;4588.357629931796;4642.373279607244;4381.4961279452955;4413.3821265022925;4469.217886869817;4497.055618320157;4526.592606665399;4448.421705178306;4465.575770301816;4474.753888536129;4544.613707834838;4573.085382923428;4487.640988720895;4489.438820147481;4489.663275254369;4490.7754612269255;4547.340635607851;4791.496892826238;4883.145449546315;4899.951000489985;4900.166971614834;4911.975440122812;5251.789928402859;5259.684418934876;5272.736363181864;5273.288106105665;5281.313429254211;5276.7889284428575;5279.973600131974;5287.915977224659;5291.021161085211;5329.094054010828;4653.395058642389;4804.303376010459;4866.245731911559;4890.923996720716;4953.950460495421;5364.22218778275;5380.811671591476;5387.191921211849;5389.865253368697;5390;5129.948700513021;5156.278121063054;5164.793408263702;5171.9482805171465;5224.16413373866;5029.346184996;5112.36095488064;5161.458046905021;5172.482751724798;5236.188390799432;5088.898222035613;5147.485251474823;5149.098907691139;5155.175171972466;5158.845234642967;5128.25640282157;5147.665401748949;5149.510796474404;5150.253213284052;5177.456367081478;4332.631726303236;4385.407969924019;4441.400410944546;4442.68901176921;4503.977480112579;3868.400397938324;3879.1480931154038;3884.2037382336553;3919.2161567686576;3925.9792453962177;4447.666425018137;4482.968917149074;4491.797869095899;4493.146302202571;4528.117017181618;5035.924461133086;5135.12702840518;5164.225366195101;5188.429272779991;5215.6609820361555;4432.047109871393;4445.799939002753;4448.1770872388715;4451.488078870907;4493.415855938772;4660.519030452672;4683.742394168354;4689.062187562433;4690.929636055462;4705.788239529229;4718.41019872516;4837.4920633333695;4883.291922671122;4885.706857588556;4947.529984651453;5227.503387178212;5255.369355677343;5256.290400795843;5263.421023687315;5267.57859371257;4882.096812089775;4891.413030436371;4909.77905994224;4913.385826771653;4914.459409464885;4975.527324904128;5092.363454568179;5124.487551244845;5130;5185.325907631984;5232.398274198364;5242.868928276824;5243.370795504564;5246.842594722164;5253.474652534716;5080.085584594808;5081.923771143362;5083.38999320084;5088.0587091388215;5109.335786347751;4822.95177048225;5002.024605202097;5054.21659642756;5074.974625126851;5149.304843846183;4814.975925120352;4991.50084991498;5003.474635163256;5018.623603229699;5067.87330316745;5106.285120083266;5130.615203859651;5139.563137133386;5144.97427512875;5150.304708864405;4872.537108974641;4929.852104436873;4968.627352948471;4993.375828021497;5047.2429135629945 09:48:16 INFO - PROCESS | 3155 | |1;ext.html;532.1884126706772;543.7390052774668;545.7816873250699;547.3978623514136;576.0523938121793;33853.15367115823;37958.240835183315;38084.23831523368;38085.80957095211;38090.42864357033;38759.80620096895;39149.608503914955;39232.21535569287;39291;39372;7568.545887246769;7674.270944260286;7735.535867847919;7736;7753.302202801747;7588.089429268495;7623.656935437919;7669.271419215165;7680.423968202379;7687.115981662097;7658.234176582325;7696.884546731803;7722.420818438612;7723.7682869514;7740.109887362969;7624.199459056802;7679.57762323072;7692.153863075057;7700;7700.921870938045;7529.887051694229;7552.66013029415;7565.243475652391;7584.848303033908;7598.468107232496;36741.63258367409;36874.262514749826;36949.81525092384;37137.44293835595;37150.62849371499;2880.7983441158917;3126.89055883043;3163.8418079095954;3172.333809899924;3253.072874230833;29221;29522.11433656994;29574;29812.552811707843;29971.550426743615;3186.1078897908624;3190.2502911815704;3221.468457704476;3258.4786434170655;3328.284418849935;268714;271972;273258;273702;273806;31223.219419514695;31291.217719557193;53033;53446;53452;32975.175620609676;33012.339753204804;33186.66813331884;33213.335733285196;33319.333613327595;12557.309347985949;12689.619311420673;12700.237985720887;12745.49018039268;12761.106722529334;10292.794144117226;10368;10374.22193335503;10375.16998640107;10392.220583456272;7743.419243556756;7764.184760600056;7800.375969922393;7808.960955195188;7812.6093695315;2974.509205981032;2994.4609970205574;3009.307859192398;3023.591815104977;3043.269615292314;846.5301757524633;887.9334049946151;902.0393281155548;934.621478301289;1018.7300365403025;27669;27933.16200513988;27960.86019569889;28261.29346766306;28484.8575757124;895.901450840407;908.2370808520742;924.7364501117149;929.809389075238;978;13141.934290328678;13860.445582176802;14057.578272651837;14136.575902722729;14229;392847;393392;394506;394521;396595;46943.29585056227;47303.526964729914;47373.289400659014;47464.7626761864;47517.28724069142;1168.094726586902;1177.711460692136;1206.2943178240653;1215.3376409856648;1220.700928272579;25372;26199.60700589531;26786;26875.73124268732;27007.1897843065;45768.54231457776;47264;47337.52662473331;51662.225066624786;51771;366227;367453;369444;370682;372617;37605.61549460562;38333.54998050189;38372.61627383802;38535;38973.220535589695;41910;42146.36780448235;42505.36241956311;42574.36138457864;43563.56436435595;35065.47401788924;36271.274574507835;36409.27181456409;36472.452913205794;37618;33895.49156762601;36689.63310366969;37294;37460.812695937435;38120.23759524739;34306;34566.82716586401;34876.12809679778;35196.47205291974;35272.294554109285;31130.844345778125;33691.15772105717;33739.15652108717;33753.662463375054;33808.49287260742;24182;24355.39111522226;24554.386140346633;24801.25596232116;24818.503629926938 09:48:16 INFO - PROCESS | 3155 | |2;jquery.html;352.8412214503474;365.75859932444575;366.1578369749576;372.6013165912475;377.9455319658154;15542.766858497132;15934.760978585316;16667.666646667076;17228.310867565284;17306.221220045096;14816.6295842604;15801.209939503036;15858.524244272661;15963.840361596409;15977.680446391094;5010.72441015744;5177.223416487538;5205.115130427817;5208.921866172011;5211.635185537014;4977.303177555127;5126.461721519243;5133.486651334874;5157.974210128944;5168.974155129219;4999.150144475448;5040.722760248185;5052.26742122392;5076.720780357079;5149.0474262261605;5083.979103394462;5116.258142569325;5128.743562821863;5129.230615407719;5141.408737995141;4567.200739870509;4592.816287348502;4597.862064138081;4602.332661764025;4610.861674149781;20755.377338679864;21290.893545532173;21335.253266135765;21520.677189842165;21630.24294149713;2153.2786516516985;2195.838627700126;2425.211806163004;2527.184807194517;2536.467341858212;13118.803217951738;13641.931790341085;13769;13901.304934753323;14229.430822767077;1761.6828970785255;1771.5571107223195;1776.2717285912713;1812.0577299804029;1862.9720554191697;69891.65054174747;69914.65042674754;69965;70761.64619176922;71026.28973710249;8499.36519678889;8584.957075214585;8928.285737141014;9761.828580570234;9958.352707074017;13218.140820846615;13271.469141234242;13286.269255190864;13328.933355333162;13355.799663005062;6933.791986240421;6970.651467426608;7116;7233.45749068822;7241.818954526179;6636.701348439332;6711.832204194837;6723.899141512881;6923.169219693669;6924.861502769916;6044.909326360108;6046.576739628272;6048.667323297261;6103.633781973096;6290.339514351019;1721.7675613792228;1759.8504127149079;1763.7795275590552;1771.60138968731;1779.4305822136791;855.2901092093548;858.9613467393982;868.1665601022962;918.8943271523726;953.8903026152082;17298.865182482674;18127.63744725098;18171.819381674282;18207.63584728298;18341;860.617025423693;871.6513394642067;878.6090189865448;897.9012308646044;900.2213085680839;9925.60297588103;10334.191516484585;10425.270231083752;10515.52680129396;10539.367637941748;385539;388450;391719;391787;393901;38899.416508752394;47190;52553;67798.66100669465;73491;898.5641963647626;913.8172365526921;927.4806108579218;939.9765005874908;946.1059298962552;16001.439949601709;16455.341786328412;16587.502374928772;16902;17197.65604687924;26036.479270414107;34485.827570861984;35933.820330898176;36285.455718164776;36823.63176368202;49109;49961.25058124059;51159.48840511547;59298;59337;7667.539947603162;7856.646450909609;7874.291313781787;8053.758387248392;8076;9669.516524173623;11564.710882228012;11775.17573769862;11963.940180299043;11965.401729913643;9613.951930240304;9896.802063958536;9946;9989;10250.641227557;10333.74165645865;10404.375737455777;10457.581696732046;10593.89406105929;10927.890721092688;9883.11052005323;9886.159676427584;9924.206063515046;9948.651797187063;10058.54736536843;7828.804279893048;7839.882401764092;7903.604819759104;7950.681972721027;8161.428699990944;4875.341828853201;8635.438696484709;9384.483853388128;9665.178459830711;10138.138258248131 09:48:16 INFO - PROCESS | 3155 | |3;mootools.html;1323.6227675112589;1336.6591519162612;1352.6618345413647;1611.8952268102573;1619.2227730689262;6370.5540612157165;7164.104486939133;7388.778336649909;7508.549487030782;7558.848823023536;7429.145648250453;7613.162552119263;7616.314531692146;7629.656665450054;7680.231976802317;2101.7267755191806;2132.2323963373174;2133.242698841912;2135.1139277200023;2145.5279838435517;2079.6360636888558;2095.6332641787703;2146.4633841539617;2146.7209262795814;2149.022194901324;2134.285014520138;2137.551114266006;2150.9892450537805;2151.515908920496;2158.6006588781047;2090.2788537954534;2095;2099.6220680277543;2102.8633138845926;2105.336818902041;2068.979310206909;2115.5663089066707;2119.682047692843;2134.5090629155225;2134.9893250533646;7470.58911759853;7541;7550.6979720811105;7559.886601700979;7578.166401695809;4262.424572682679;4397.1205758848355;4399.780010999437;4400.251957167289;4401.163778882034;2749.917502474929;2800.929976750577;2818.788590855694;2848.1170837040586;2889.942201155965;870.1907226279558;891.3092353426082;898.6225785170244;900.5047224026824;902.1564836877541;10382.84425733607;10411.791764164751;10443.68668939933;10447.895521044768;10449.425281609509;3829.1384438501673;4002.699797515198;4076.5923407658997;4098.549159592442;4145.0880806222585;8905.73282801517;8907.688230911886;8935.687250946186;8938.329625278131;8944.418612790148;4340.066885619607;4376.737395756265;4385.868423947287;4409.625181859577;4419.315006174048;3315;3956.1296514766705;4058.797060146981;4064.4716186895516;4065.7763822989427;3532.3111993161087;3571.3035957987945;3610.3140403323287;3642.581103173169;3671.761335513183;1136.9540023178695;1142.5429828068839;1149.230015889356;1162.511745067074;1166.2419427372276;591.9052951527735;613.8588124731336;686.1800148822109;692.8926016467456;707.9787606371817;4775.6657034007985;4838.806447742121;4978.054169707744;4979.103761322925;5178.896422071537;843.4306842881153;863.3524856357732;913.9398297974318;929.2101713543569;932.8991789688233;3286.2277364819247;3319.8174100424226;3360.8655653774063;3431.296584200233;3452.7065199457825;9105.135012173832;9298.90701092994;9554.378965367228;9558;9565.90434095664;9322.20761235289;9338.906610933804;9339.252859771203;9342.112499312554;9375.484348360906;7082.752103676449;7119.679614417364;7221.855562888818;7288.8177795554475;7316.561006339637;4951.653384263067;4978.751062446936;4988.850334489971;5021.322121513549;5104.489551044866;8661.653533858575;8720.12798720123;8751.431156974777;8796.384253102222;8840.939087309564;8830.514321712368;8874.556272186494;8993.640254389751;8996.59515321799;9053.366264361432;5504.779808807763;6198.256209254918;6220.906686399617;6341.985282354801;6380.553361264667;7008.22909479967;7026.332498412643;7039.9296007039275;7044.36600705939;7062.752803651848;6233.283172435046;6412;6441.452476539546;6603.306652801387;6609.339066093352;6111.938880611137;6330.683465826783;6387.8722425550295;6449;6469.320721324381;5843.590948633555;5909.6158749681135;5911.497522710618;5914.497267732291;5941.37615550361;4350.782460877007;4398.626116779981;4426.336049592587;4481.955180448284;4520.141173177086;4314.460692413449;4367.235733746518;4529.954700452953;4562;4586.977065114653 09:48:16 INFO - PROCESS | 3155 | |4;prototype.html;276.5961695923951;282.3660881321433;284.49644129890083;286.999805677215;292.53340921230637;4905.754712264382;5033.245013248014;5037.924431133536;5101.209312556551;5145.041601079376;5037.445880953102;5048.3437153170125;5061.873453163673;5063.911259079297;5074.746262686869;3976.6421022108007;3980.4029395590605;3981.6814654827544;3983.6414722674954;3989.68082553395;3862.2854771867155;3947.8748556661358;3985.760854348734;3988.461557689718;3991.0421498840215;3896.629820167094;3899.1032062625613;3909.2963266612;3911.7261791674596;3932.6853851691794;3891.665000999392;3899.2396482685886;3919.667313113555;3930.155016671429;3982.26328129297;3209.3741720364537;3336.96663033369;3340.2484441000825;3460.083077984311;3462.515247865301;4874.878128046792;4913.248580794766;4913.582345500637;4916.532929371704;4949.925751113737;4375.803088860977;4385.2106620808245;4410.713303635285;4432.429191069172;4442.311441726538;5017.799288028475;5209.244659524347;5222.268882356473;5284.180951952453;5292.629515933886;3624.836882340301;3636.218213084173;3638.7810083622016;3642.963570364289;3651.8721844735574;5498.532624726903;5516.889662206733;5535.225068490415;5542.168674698787;5586.86032849178;3689.1330537323706;3752.1932784451474;3785.072657198949;3791.4123310886857;3792.3742582473583;3780.6597406233573;3803.9429408558894;3807.371783655721;3816.9427458588143;3836.961630383716;4374.212641724456;4399.824007039747;4420.138073075784;4428.962677168474;4440.289553671397;5298.8207075754235;5315.309009828775;5330;5355.250264962909;5380.946190538045;1272.2239433945285;1295.1322613848745;1317;1322.1207896748747;1327.6548097494722;814.9877751833727;822.4160845799549;826.6114925984778;833.5415521463292;836.6611522333465;4137.731047481964;4140.792960351971;4168.8124034417915;4174.269502836992;4185.330347144442;4752.809887604457;4756.833510827174;4779.777403507385;4829.66192366538;4864.513548645107;4064.7154699170774;4079.3897400800506;4087.7547347159266;4160.91678166441;4163.584381310308;2591.8704064796684;2683.4901368740266;2899;2930.369970456362;2936.4420760055523;5135.820246291437;5158.92261616076;5160.690358578422;5166.9224961625605;5168.508991645787;5398.406175320712;5619.494245517923;5647.576431767634;5650.174912543728;5650.208970744099;5092.719900405439;5108.28484012246;5108.335916330928;5120.876265478056;5129;5486.561075114061;5493.599132221258;5525.806596769094;5533.529649979797;5546.417626149197;5099.69401835891;5375.5162035417;5418.918716219338;5423.2407462954425;5423.701696406735;5137.09753988995;5144.459831717616;5152.845414637567;5153.484651534816;5160.380754309357;3510.754247202671;3652.379095553815;3733.197362567061;3796.7911764853197;3805.2389522095136;5412.485813847678;5420.783168673209;5432.44675002748;5435.891282174255;5473.589480788877;5188.728761453468;5274.8945021100135;5298.54962328191;5308.522232999048;5314.867128321823;5126.948975460096;5215.921761173661;5253.605979551472;5282.418933917189;5297.913927644747;5185.651730550008;5191.896162076813;5206;5207.92188117171;5213.661112027706;5279.260903473593;5304.761285742228;5305.469453054664;5316.574674026146;5416.889537644852;4984.4517103117905;5225.973870130625;5233.371995360581;5288.550473209821;5306.363236411656 09:48:16 INFO - PROCESS | 3155 | |5;yui.html;475.9309900064493;476.9618430525558;652.6540933305348;679.2629996453848;714.2464699741773;11261.66215013549;12494.56269030583;13850;13916.930415347922;14575.198364089998;13674;14781.039232449883;14825.110493370405;14852.18312992785;14909.40362385506;732.527519749761;757.3713817531436;764.9541027538365;766.337118392592;767.1139833492325;750.8423231121471;753.0511555440157;755.8526087412956;755.8752805787038;757.4773406349628;803.7468197517763;804.891339669146;805.899262592176;806.7620052084649;807.0557447786105;770.2143813310392;800.0878997942292;801.3589128697048;801.707376807464;803.7267329108131;565.3893794701753;568.0938902450584;572.5791543215734;573.2891214893543;574.2792795042237;12802.48790048397;12850.614481565568;12888.420021099073;12921.160124591965;13016.219026858418;13022.153560018663;13255;13703.520376786764;13747.518836840763;14034.087784293988;10042.246831487595;10285.537150828231;10335.379877207391;10345.844812327821;10426.530806113744;3223.5003574446;3253.316803471274;3294.3081952789944;3295.472724364114;3345.1302661308237;65236.34763652398;65687.34312656907;65842.34157658363;66583.66708166477;66634.33365666379;9322.25421966241;9468.100530449596;9659.32384733062;9782.070703283176;9814.263930205263;19024.80975190258;19113.33103341404;19152.90423547901;19162.425127246206;19428;6617.073609694647;6641.435477984329;6645.867082658319;6726.764563240263;6775.898361524581;3666.8533258669354;3694.9815250923575;3723;3726.5900750917376;3740.345439548068;2901.8113822601463;3007;3008.0524634739986;3009.9247518811767;3188.3623275345026;522.4357693690805;524.8268071536384;531.0759278854765;532.8374845672107;535.807109440601;437.85331913809233;460.86146405280766;497.42795784846993;530.1888111189886;544.5725105791935;7647;7837.787871065678;8425.283850872622;8437;8554.27288680469;452.75098695717224;473.30187972740623;498.4492136189531;526.5632346027207;528.7250629672549;3823.235352929425;3843.8462461501226;3901.51231096113;3906.628870257321;4018.718689691752;104589.47705261425;105999.47000265104;109878.45060774645;111410;111780.44109779398;92431;94591;97179;98923.50538247262;99055;87204;87766;88143.55928220446;89377;90023;7323.780286591411;7425.331720145213;7450.143233527997;7533.246675332643;7610.277023682741;46614.30078548757;47040.529594703614;47978.28032579444;48763.367480089335;49342;66869;72107.27892721216;72547;72787.63606181936;74022;9804.803903921738;9897.455639939872;9939.801203976025;10001.799964000826;10021.298509104292;11239.10087193039;11397.715057123638;12149.817752733892;12434.316112613893;12522.186057906207;11623.360715160747;11626.941865290619;11825.40872956366;12023.879761202626;12313.507459701512;11294.60468883585;11492.655220343404;11534.192606517463;11724.531018759495;12121.515139394678;11895.167338286237;11977.281363117892;12121.333326667118;12280.938595306967;12493.312867792358;9353.859692104488;9556.522173891417;9827.606895724091;9978.700638980843;10227.94886025565;9345.579448924678;10081.193504519768;10312.329698569569;10485.633002844865;10531.789364212826 09:48:16 INFO - PROCESS | 3155 | __end_tp_report 09:48:16 INFO - PROCESS | 3155 | __start_cc_report 09:48:16 INFO - PROCESS | 3155 | _x_x_mozilla_cycle_collect,402 09:48:16 INFO - PROCESS | 3155 | __end_cc_report 09:48:16 INFO - PROCESS | 3155 | __startTimestamp1470674896343__endTimestamp 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | ------- Summary: start ------- 09:48:16 INFO - PROCESS | 3155 | Number of tests: 6 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | [#0] dojo.html Cycles:165 Average:4811.94 Median:4913.39 stddev:505.71 (10.3%) stddev-sans-first:478.44 09:48:16 INFO - PROCESS | 3155 | Values: 2666.8 2667.7 2706.3 2709.6 2711.7 4716.5 4765.4 4904.4 4939.8 4944.7 4755.4 4811.2 4827.6 4859.5 4877.7 4411.7 4461.0 4488.4 4496.3 4590.4 4537.6 4546.6 4588.1 4588.4 4642.4 4381.5 4413.4 4469.2 4497.1 4526.6 4448.4 4465.6 4474.8 4544.6 4573.1 4487.6 4489.4 4489.7 4490.8 4547.3 4791.5 4883.1 4900.0 4900.2 4912.0 5251.8 5259.7 5272.7 5273.3 5281.3 5276.8 5280.0 5287.9 5291.0 5329.1 4653.4 4804.3 4866.2 4890.9 4954.0 5364.2 5380.8 5387.2 5389.9 5390.0 5129.9 5156.3 5164.8 5171.9 5224.2 5029.3 5112.4 5161.5 5172.5 5236.2 5088.9 5147.5 5149.1 5155.2 5158.8 5128.3 5147.7 5149.5 5150.3 5177.5 4332.6 4385.4 4441.4 4442.7 4504.0 3868.4 3879.1 3884.2 3919.2 3926.0 4447.7 4483.0 4491.8 4493.1 4528.1 5035.9 5135.1 5164.2 5188.4 5215.7 4432.0 4445.8 4448.2 4451.5 4493.4 4660.5 4683.7 4689.1 4690.9 4705.8 4718.4 4837.5 4883.3 4885.7 4947.5 5227.5 5255.4 5256.3 5263.4 5267.6 4882.1 4891.4 4909.8 4913.4 4914.5 4975.5 5092.4 5124.5 5130.0 5185.3 5232.4 5242.9 5243.4 5246.8 5253.5 5080.1 5081.9 5083.4 5088.1 5109.3 4823.0 5002.0 5054.2 5075.0 5149.3 4815.0 4991.5 5003.5 5018.6 5067.9 5106.3 5130.6 5139.6 5145.0 5150.3 4872.5 4929.9 4968.6 4993.4 5047.2 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | [#1] ext.html Cycles:180 Average:48602.37 Median:203815.91 stddev:92332.88 (45.3%) stddev-sans-first:92521.36 09:48:16 INFO - PROCESS | 3155 | Values: 532.2 543.7 545.8 547.4 576.1 33853.2 37958.2 38084.2 38085.8 38090.4 38759.8 39149.6 39232.2 39291.0 39372.0 7568.5 7674.3 7735.5 7736.0 7753.3 7588.1 7623.7 7669.3 7680.4 7687.1 7658.2 7696.9 7722.4 7723.8 7740.1 7624.2 7679.6 7692.2 7700.0 7700.9 7529.9 7552.7 7565.2 7584.8 7598.5 36741.6 36874.3 36949.8 37137.4 37150.6 2880.8 3126.9 3163.8 3172.3 3253.1 29221.0 29522.1 29574.0 29812.6 29971.6 3186.1 3190.3 3221.5 3258.5 3328.3 268714.0 271972.0 273258.0 273702.0 273806.0 31223.2 31291.2 53033.0 53446.0 53452.0 32975.2 33012.3 33186.7 33213.3 33319.3 12557.3 12689.6 12700.2 12745.5 12761.1 10292.8 10368.0 10374.2 10375.2 10392.2 7743.4 7764.2 7800.4 7809.0 7812.6 2974.5 2994.5 3009.3 3023.6 3043.3 846.5 887.9 902.0 934.6 1018.7 27669.0 27933.2 27960.9 28261.3 28484.9 895.9 908.2 924.7 929.8 978.0 13141.9 13860.4 14057.6 14136.6 14229.0 392847.0 393392.0 394506.0 394521.0 396595.0 46943.3 47303.5 47373.3 47464.8 47517.3 1168.1 1177.7 1206.3 1215.3 1220.7 25372.0 26199.6 26786.0 26875.7 27007.2 45768.5 47264.0 47337.5 51662.2 51771.0 366227.0 367453.0 369444.0 370682.0 372617.0 37605.6 38333.5 38372.6 38535.0 38973.2 41910.0 42146.4 42505.4 42574.4 43563.6 35065.5 36271.3 36409.3 36472.5 37618.0 33895.5 36689.6 37294.0 37460.8 38120.2 34306.0 34566.8 34876.1 35196.5 35272.3 31130.8 33691.2 33739.2 33753.7 33808.5 24182.0 24355.4 24554.4 24801.3 24818.5 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | [#2] jquery.html Cycles:180 Average:23866.43 Median:27469.28 stddev:64120.86 (233.4%) stddev-sans-first:64276.43 09:48:16 INFO - PROCESS | 3155 | Values: 352.8 365.8 366.2 372.6 377.9 15542.8 15934.8 16667.7 17228.3 17306.2 14816.6 15801.2 15858.5 15963.8 15977.7 5010.7 5177.2 5205.1 5208.9 5211.6 4977.3 5126.5 5133.5 5158.0 5169.0 4999.2 5040.7 5052.3 5076.7 5149.0 5084.0 5116.3 5128.7 5129.2 5141.4 4567.2 4592.8 4597.9 4602.3 4610.9 20755.4 21290.9 21335.3 21520.7 21630.2 2153.3 2195.8 2425.2 2527.2 2536.5 13118.8 13641.9 13769.0 13901.3 14229.4 1761.7 1771.6 1776.3 1812.1 1863.0 69891.7 69914.7 69965.0 70761.6 71026.3 8499.4 8585.0 8928.3 9761.8 9958.4 13218.1 13271.5 13286.3 13328.9 13355.8 6933.8 6970.7 7116.0 7233.5 7241.8 6636.7 6711.8 6723.9 6923.2 6924.9 6044.9 6046.6 6048.7 6103.6 6290.3 1721.8 1759.9 1763.8 1771.6 1779.4 855.3 859.0 868.2 918.9 953.9 17298.9 18127.6 18171.8 18207.6 18341.0 860.6 871.7 878.6 897.9 900.2 9925.6 10334.2 10425.3 10515.5 10539.4 385539.0 388450.0 391719.0 391787.0 393901.0 38899.4 47190.0 52553.0 67798.7 73491.0 898.6 913.8 927.5 940.0 946.1 16001.4 16455.3 16587.5 16902.0 17197.7 26036.5 34485.8 35933.8 36285.5 36823.6 49109.0 49961.3 51159.5 59298.0 59337.0 7667.5 7856.6 7874.3 8053.8 8076.0 9669.5 11564.7 11775.2 11963.9 11965.4 9614.0 9896.8 9946.0 9989.0 10250.6 10333.7 10404.4 10457.6 10593.9 10927.9 9883.1 9886.2 9924.2 9948.7 10058.5 7828.8 7839.9 7903.6 7950.7 8161.4 4875.3 8635.4 9384.5 9665.2 10138.1 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | [#3] mootools.html Cycles:180 Average:4951.13 Median:4983.98 stddev:2800.37 (56.2%) stddev-sans-first:2794.96 09:48:16 INFO - PROCESS | 3155 | Values: 1323.6 1336.7 1352.7 1611.9 1619.2 6370.6 7164.1 7388.8 7508.5 7558.8 7429.1 7613.2 7616.3 7629.7 7680.2 2101.7 2132.2 2133.2 2135.1 2145.5 2079.6 2095.6 2146.5 2146.7 2149.0 2134.3 2137.6 2151.0 2151.5 2158.6 2090.3 2095.0 2099.6 2102.9 2105.3 2069.0 2115.6 2119.7 2134.5 2135.0 7470.6 7541.0 7550.7 7559.9 7578.2 4262.4 4397.1 4399.8 4400.3 4401.2 2749.9 2800.9 2818.8 2848.1 2889.9 870.2 891.3 898.6 900.5 902.2 10382.8 10411.8 10443.7 10447.9 10449.4 3829.1 4002.7 4076.6 4098.5 4145.1 8905.7 8907.7 8935.7 8938.3 8944.4 4340.1 4376.7 4385.9 4409.6 4419.3 3315.0 3956.1 4058.8 4064.5 4065.8 3532.3 3571.3 3610.3 3642.6 3671.8 1137.0 1142.5 1149.2 1162.5 1166.2 591.9 613.9 686.2 692.9 708.0 4775.7 4838.8 4978.1 4979.1 5178.9 843.4 863.4 913.9 929.2 932.9 3286.2 3319.8 3360.9 3431.3 3452.7 9105.1 9298.9 9554.4 9558.0 9565.9 9322.2 9338.9 9339.3 9342.1 9375.5 7082.8 7119.7 7221.9 7288.8 7316.6 4951.7 4978.8 4988.9 5021.3 5104.5 8661.7 8720.1 8751.4 8796.4 8840.9 8830.5 8874.6 8993.6 8996.6 9053.4 5504.8 6198.3 6220.9 6342.0 6380.6 7008.2 7026.3 7039.9 7044.4 7062.8 6233.3 6412.0 6441.5 6603.3 6609.3 6111.9 6330.7 6387.9 6449.0 6469.3 5843.6 5909.6 5911.5 5914.5 5941.4 4350.8 4398.6 4426.3 4482.0 4520.1 4314.5 4367.2 4530.0 4562.0 4587.0 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | [#4] prototype.html Cycles:180 Average:4328.15 Median:4967.19 stddev:1290.35 (26.0%) stddev-sans-first:1257.63 09:48:16 INFO - PROCESS | 3155 | Values: 276.6 282.4 284.5 287.0 292.5 4905.8 5033.2 5037.9 5101.2 5145.0 5037.4 5048.3 5061.9 5063.9 5074.7 3976.6 3980.4 3981.7 3983.6 3989.7 3862.3 3947.9 3985.8 3988.5 3991.0 3896.6 3899.1 3909.3 3911.7 3932.7 3891.7 3899.2 3919.7 3930.2 3982.3 3209.4 3337.0 3340.2 3460.1 3462.5 4874.9 4913.2 4913.6 4916.5 4949.9 4375.8 4385.2 4410.7 4432.4 4442.3 5017.8 5209.2 5222.3 5284.2 5292.6 3624.8 3636.2 3638.8 3643.0 3651.9 5498.5 5516.9 5535.2 5542.2 5586.9 3689.1 3752.2 3785.1 3791.4 3792.4 3780.7 3803.9 3807.4 3816.9 3837.0 4374.2 4399.8 4420.1 4429.0 4440.3 5298.8 5315.3 5330.0 5355.3 5380.9 1272.2 1295.1 1317.0 1322.1 1327.7 815.0 822.4 826.6 833.5 836.7 4137.7 4140.8 4168.8 4174.3 4185.3 4752.8 4756.8 4779.8 4829.7 4864.5 4064.7 4079.4 4087.8 4160.9 4163.6 2591.9 2683.5 2899.0 2930.4 2936.4 5135.8 5158.9 5160.7 5166.9 5168.5 5398.4 5619.5 5647.6 5650.2 5650.2 5092.7 5108.3 5108.3 5120.9 5129.0 5486.6 5493.6 5525.8 5533.5 5546.4 5099.7 5375.5 5418.9 5423.2 5423.7 5137.1 5144.5 5152.8 5153.5 5160.4 3510.8 3652.4 3733.2 3796.8 3805.2 5412.5 5420.8 5432.4 5435.9 5473.6 5188.7 5274.9 5298.5 5308.5 5314.9 5126.9 5215.9 5253.6 5282.4 5297.9 5185.7 5191.9 5206.0 5207.9 5213.7 5279.3 5304.8 5305.5 5316.6 5416.9 4984.5 5226.0 5233.4 5288.6 5306.4 09:48:16 INFO - PROCESS | 3155 | 09:48:16 INFO - PROCESS | 3155 | [#5] yui.html Cycles:180 Average:19226.94 Median:48370.82 stddev:28876.85 (59.7%) stddev-sans-first:28923.54 09:48:16 INFO - PROCESS | 3155 | Values: 475.9 477.0 652.7 679.3 714.2 11261.7 12494.6 13850.0 13916.9 14575.2 13674.0 14781.0 14825.1 14852.2 14909.4 732.5 757.4 765.0 766.3 767.1 750.8 753.1 755.9 755.9 757.5 803.7 804.9 805.9 806.8 807.1 770.2 800.1 801.4 801.7 803.7 565.4 568.1 572.6 573.3 574.3 12802.5 12850.6 12888.4 12921.2 13016.2 13022.2 13255.0 13703.5 13747.5 14034.1 10042.2 10285.5 10335.4 10345.8 10426.5 3223.5 3253.3 3294.3 3295.5 3345.1 65236.3 65687.3 65842.3 66583.7 66634.3 9322.3 9468.1 9659.3 9782.1 9814.3 19024.8 19113.3 19152.9 19162.4 19428.0 6617.1 6641.4 6645.9 6726.8 6775.9 3666.9 3695.0 3723.0 3726.6 3740.3 2901.8 3007.0 3008.1 3009.9 3188.4 522.4 524.8 531.1 532.8 535.8 437.9 460.9 497.4 530.2 544.6 7647.0 7837.8 8425.3 8437.0 8554.3 452.8 473.3 498.4 526.6 528.7 3823.2 3843.8 3901.5 3906.6 4018.7 104589.5 105999.5 109878.5 111410.0 111780.4 92431.0 94591.0 97179.0 98923.5 99055.0 87204.0 87766.0 88143.6 89377.0 90023.0 7323.8 7425.3 7450.1 7533.2 7610.3 46614.3 47040.5 47978.3 48763.4 49342.0 66869.0 72107.3 72547.0 72787.6 74022.0 9804.8 9897.5 9939.8 10001.8 10021.3 11239.1 11397.7 12149.8 12434.3 12522.2 11623.4 11626.9 11825.4 12023.9 12313.5 11294.6 11492.7 11534.2 11724.5 12121.5 11895.2 11977.3 12121.3 12280.9 12493.3 9353.9 9556.5 9827.6 9978.7 10227.9 9345.6 10081.2 10312.3 10485.6 10531.8 09:48:16 INFO - PROCESS | 3155 | -------- Summary: end -------- 09:48:16 INFO - PROCESS | 3155 | 09:48:17 INFO - TEST-INFO | 3155: exit 0 09:48:17 INFO - TEST-OK | dromaeo_css | took 1116855ms 09:48:17 INFO - TEST-START | kraken 09:48:17 INFO - operating with platform_type : linux_ 09:48:17 INFO - Initialising browser for kraken test... 09:48:17 INFO - TEST-INFO | started process 3395 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpunTNkS/profile http://localhost:41575/getInfo.html) 09:48:21 INFO - PROCESS | 3395 | __metrics Screen width/height:1600/1200 09:48:21 INFO - PROCESS | 3395 | colorDepth:24 09:48:21 INFO - PROCESS | 3395 | Browser inner width/height: 1024/697 09:48:21 INFO - PROCESS | 3395 | __metrics 09:48:22 ERROR - PROCESS | 3395 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 09:48:22 INFO - TEST-INFO | 3395: exit 0 09:48:22 INFO - Browser initialized. 09:48:22 INFO - Running cycle 1/1 for kraken test... 09:48:22 INFO - TEST-INFO | started process 3517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpunTNkS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:48:34 INFO - PROCESS | 3517 | RSS: Main: 165294080 09:48:34 INFO - PROCESS | 3517 | 09:48:37 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41575/tests/kraken/driver.html?testName=audio-beat-detection) 09:48:38 INFO - PROCESS | 3517 | RSS: Main: 169951232 09:48:38 INFO - PROCESS | 3517 | 09:48:41 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41575/tests/kraken/driver.html?testName=audio-dft) 09:48:41 INFO - PROCESS | 3517 | RSS: Main: 169017344 09:48:41 INFO - PROCESS | 3517 | 09:48:44 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41575/tests/kraken/driver.html?testName=audio-fft) 09:48:45 INFO - PROCESS | 3517 | RSS: Main: 165359616 09:48:45 INFO - PROCESS | 3517 | 09:48:47 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41575/tests/kraken/driver.html?testName=audio-oscillator) 09:48:48 INFO - PROCESS | 3517 | RSS: Main: 165904384 09:48:48 INFO - PROCESS | 3517 | 09:48:50 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41575/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:48:51 INFO - PROCESS | 3517 | RSS: Main: 166965248 09:48:51 INFO - PROCESS | 3517 | 09:48:54 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41575/tests/kraken/driver.html?testName=imaging-darkroom) 09:48:55 INFO - PROCESS | 3517 | RSS: Main: 166473728 09:48:55 INFO - PROCESS | 3517 | 09:48:58 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41575/tests/kraken/driver.html?testName=imaging-desaturate) 09:48:59 INFO - PROCESS | 3517 | RSS: Main: 163983360 09:48:59 INFO - PROCESS | 3517 | 09:49:02 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41575/tests/kraken/driver.html?testName=json-parse-financial) 09:49:02 INFO - PROCESS | 3517 | RSS: Main: 165642240 09:49:02 INFO - PROCESS | 3517 | 09:49:04 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41575/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:49:05 INFO - PROCESS | 3517 | RSS: Main: 165421056 09:49:05 INFO - PROCESS | 3517 | 09:49:07 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:49:07 INFO - PROCESS | 3517 | RSS: Main: 165670912 09:49:07 INFO - PROCESS | 3517 | 09:49:10 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:49:10 INFO - PROCESS | 3517 | RSS: Main: 166047744 09:49:10 INFO - PROCESS | 3517 | 09:49:13 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:49:14 INFO - PROCESS | 3517 | RSS: Main: 166719488 09:49:14 INFO - PROCESS | 3517 | 09:49:17 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:49:17 INFO - PROCESS | 3517 | RSS: Main: 166764544 09:49:17 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | Cycle 1(1): loaded http://localhost:41575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41575/tests/kraken/driver.html?testName=ai-astar) 09:49:19 INFO - PROCESS | 3517 | RSS: Main: 167641088 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | __start_tp_report 09:49:19 INFO - PROCESS | 3517 | _x_x_mozilla_page_load 09:49:19 INFO - PROCESS | 3517 | _x_x_mozilla_page_load_details 09:49:19 INFO - PROCESS | 3517 | |i|pagename|runs| 09:49:19 INFO - PROCESS | 3517 | |0;ai-astar;105;116;110;114;114;114;116;114;115;114 09:49:19 INFO - PROCESS | 3517 | |1;audio-beat-detection;171;122;152;186;127;146;158;124;205;125 09:49:19 INFO - PROCESS | 3517 | |2;audio-dft;163;164;166;175;165;163;163;166;164;162 09:49:19 INFO - PROCESS | 3517 | |3;audio-fft;77;77;77;185;76;76;76;77;165;78 09:49:19 INFO - PROCESS | 3517 | |4;audio-oscillator;86;87;161;85;86;86;134;85;84;144 09:49:19 INFO - PROCESS | 3517 | |5;imaging-gaussian-blur;118;118;119;117;117;118;117;118;117;123 09:49:19 INFO - PROCESS | 3517 | |6;imaging-darkroom;149;148;150;148;147;148;148;149;149;148 09:49:19 INFO - PROCESS | 3517 | |7;imaging-desaturate;95;96;96;96;95;96;97;96;96;95 09:49:19 INFO - PROCESS | 3517 | |8;json-parse-financial;65;68;65;64;66;65;66;64;64;64 09:49:19 INFO - PROCESS | 3517 | |9;json-stringify-tinderbox;65;66;65;65;67;66;65;65;65;65 09:49:19 INFO - PROCESS | 3517 | |10;stanford-crypto-aes;91;81;87;83;86;80;79;79;84;80 09:49:19 INFO - PROCESS | 3517 | |11;stanford-crypto-ccm;142;140;143;136;142;134;144;148;164;132 09:49:19 INFO - PROCESS | 3517 | |12;stanford-crypto-pbkdf2;174;166;174;175;174;168;168;166;166;165 09:49:19 INFO - PROCESS | 3517 | |13;stanford-crypto-sha256-iterative;69;72;68;68;68;70;67;67;68;68 09:49:19 INFO - PROCESS | 3517 | __end_tp_report 09:49:19 INFO - PROCESS | 3517 | __start_cc_report 09:49:19 INFO - PROCESS | 3517 | _x_x_mozilla_cycle_collect,960 09:49:19 INFO - PROCESS | 3517 | __end_cc_report 09:49:19 INFO - PROCESS | 3517 | __startTimestamp1470674959934__endTimestamp 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | ------- Summary: start ------- 09:49:19 INFO - PROCESS | 3517 | Number of tests: 14 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:3.33 (2.9%) stddev-sans-first:1.76 09:49:19 INFO - PROCESS | 3517 | Values: 105.0 116.0 110.0 114.0 114.0 114.0 116.0 114.0 115.0 114.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#1] audio-beat-detection Cycles:10 Average:151.60 Median:155.00 stddev:28.74 (18.5%) stddev-sans-first:29.61 09:49:19 INFO - PROCESS | 3517 | Values: 171.0 122.0 152.0 186.0 127.0 146.0 158.0 124.0 205.0 125.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#2] audio-dft Cycles:10 Average:165.10 Median:164.50 stddev:3.73 (2.3%) stddev-sans-first:3.87 09:49:19 INFO - PROCESS | 3517 | Values: 163.0 164.0 166.0 175.0 165.0 163.0 163.0 166.0 164.0 162.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#3] audio-fft Cycles:10 Average:96.40 Median:77.00 stddev:41.70 (54.2%) stddev-sans-first:43.63 09:49:19 INFO - PROCESS | 3517 | Values: 77.0 77.0 77.0 185.0 76.0 76.0 76.0 77.0 165.0 78.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#4] audio-oscillator Cycles:10 Average:103.80 Median:85.50 stddev:30.06 (35.2%) stddev-sans-first:31.18 09:49:19 INFO - PROCESS | 3517 | Values: 86.0 87.0 161.0 85.0 86.0 86.0 134.0 85.0 84.0 144.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#5] imaging-gaussian-blur Cycles:10 Average:118.20 Median:118.00 stddev:1.81 (1.5%) stddev-sans-first:1.92 09:49:19 INFO - PROCESS | 3517 | Values: 118.0 118.0 119.0 117.0 117.0 118.0 117.0 118.0 117.0 123.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 09:49:19 INFO - PROCESS | 3517 | Values: 149.0 148.0 150.0 148.0 147.0 148.0 148.0 149.0 149.0 148.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 09:49:19 INFO - PROCESS | 3517 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 97.0 96.0 96.0 95.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.00 stddev:1.29 (2.0%) stddev-sans-first:1.36 09:49:19 INFO - PROCESS | 3517 | Values: 65.0 68.0 65.0 64.0 66.0 65.0 66.0 64.0 64.0 64.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.00 stddev:0.70 (1.1%) stddev-sans-first:0.73 09:49:19 INFO - PROCESS | 3517 | Values: 65.0 66.0 65.0 65.0 67.0 66.0 65.0 65.0 65.0 65.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:4.00 (4.8%) stddev-sans-first:3.02 09:49:19 INFO - PROCESS | 3517 | Values: 91.0 81.0 87.0 83.0 86.0 80.0 79.0 79.0 84.0 80.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:142.50 stddev:8.98 (6.3%) stddev-sans-first:9.53 09:49:19 INFO - PROCESS | 3517 | Values: 142.0 140.0 143.0 136.0 142.0 134.0 144.0 148.0 164.0 132.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:171.00 stddev:4.12 (2.4%) stddev-sans-first:4.04 09:49:19 INFO - PROCESS | 3517 | Values: 174.0 166.0 174.0 175.0 174.0 168.0 168.0 166.0 166.0 165.0 09:49:19 INFO - PROCESS | 3517 | 09:49:19 INFO - PROCESS | 3517 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.59 09:49:19 INFO - PROCESS | 3517 | Values: 69.0 72.0 68.0 68.0 68.0 70.0 67.0 67.0 68.0 68.0 09:49:19 INFO - PROCESS | 3517 | -------- Summary: end -------- 09:49:19 INFO - PROCESS | 3517 | 09:49:20 INFO - TEST-INFO | 3517: exit 0 09:49:21 INFO - TEST-OK | kraken | took 63991ms 09:49:21 INFO - SUITE-END | took 1180s 09:49:21 INFO - Completed test suite (00:19:40) 09:49:21 INFO - javascript benchmark 09:49:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6359.48666964555, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.79999000075, 2667.70655227925, 2706.336947447875, 2709.5800150976593, 2711.7017128115904, 4716.457607375144, 4765.356676848624, 4904.411470623521, 4939.802407903689, 4944.653874228801, 4755.405574303212, 4811.2302031674935, 4827.58965487934, 4859.514048595139, 4877.658563900528, 4411.69118161728, 4461.040876211614, 4488.416505854234, 4496.280595104784, 4590.449146102455, 4537.614302784267, 4546.568076032771, 4588.082383523292, 4588.357629931796, 4642.373279607244, 4381.4961279452955, 4413.3821265022925, 4469.217886869817, 4497.055618320157, 4526.592606665399, 4448.421705178306, 4465.575770301816, 4474.753888536129, 4544.613707834838, 4573.085382923428, 4487.640988720895, 4489.438820147481, 4489.663275254369, 4490.7754612269255, 4547.340635607851, 4791.496892826238, 4883.145449546315, 4899.951000489985, 4900.166971614834, 4911.975440122812, 5251.789928402859, 5259.684418934876, 5272.736363181864, 5273.288106105665, 5281.313429254211, 5276.7889284428575, 5279.973600131974, 5287.915977224659, 5291.021161085211, 5329.094054010828, 4653.395058642389, 4804.303376010459, 4866.245731911559, 4890.923996720716, 4953.950460495421, 5364.22218778275, 5380.811671591476, 5387.191921211849, 5389.865253368697, 5390.0, 5129.948700513021, 5156.278121063054, 5164.793408263702, 5171.9482805171465, 5224.16413373866, 5029.346184996, 5112.36095488064, 5161.458046905021, 5172.482751724798, 5236.188390799432, 5088.898222035613, 5147.485251474823, 5149.098907691139, 5155.175171972466, 5158.845234642967, 5128.25640282157, 5147.665401748949, 5149.510796474404, 5150.253213284052, 5177.456367081478, 4332.631726303236, 4385.407969924019, 4441.400410944546, 4442.68901176921, 4503.977480112579, 3868.400397938324, 3879.1480931154038, 3884.2037382336553, 3919.2161567686576, 3925.9792453962177, 4447.666425018137, 4482.968917149074, 4491.797869095899, 4493.146302202571, 4528.117017181618, 5035.924461133086, 5135.12702840518, 5164.225366195101, 5188.429272779991, 5215.6609820361555, 4432.047109871393, 4445.799939002753, 4448.1770872388715, 4451.488078870907, 4493.415855938772, 4660.519030452672, 4683.742394168354, 4689.062187562433, 4690.929636055462, 4705.788239529229, 4718.41019872516, 4837.4920633333695, 4883.291922671122, 4885.706857588556, 4947.529984651453, 5227.503387178212, 5255.369355677343, 5256.290400795843, 5263.421023687315, 5267.57859371257, 4882.096812089775, 4891.413030436371, 4909.77905994224, 4913.385826771653, 4914.459409464885, 4975.527324904128, 5092.363454568179, 5124.487551244845, 5130.0, 5185.325907631984, 5232.398274198364, 5242.868928276824, 5243.370795504564, 5246.842594722164, 5253.474652534716, 5080.085584594808, 5081.923771143362, 5083.38999320084, 5088.0587091388215, 5109.335786347751, 4822.95177048225, 5002.024605202097, 5054.21659642756, 5074.974625126851, 5149.304843846183, 4814.975925120352, 4991.50084991498, 5003.474635163256, 5018.623603229699, 5067.87330316745, 5106.285120083266, 5130.615203859651, 5139.563137133386, 5144.97427512875, 5150.304708864405, 4872.537108974641, 4929.852104436873, 4968.627352948471, 4993.375828021497, 5047.2429135629945], "value": 4779.440380412554, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.1884126706772, 543.7390052774668, 545.7816873250699, 547.3978623514136, 576.0523938121793, 33853.15367115823, 37958.240835183315, 38084.23831523368, 38085.80957095211, 38090.42864357033, 38759.80620096895, 39149.608503914955, 39232.21535569287, 39291.0, 39372.0, 7568.545887246769, 7674.270944260286, 7735.535867847919, 7736.0, 7753.302202801747, 7588.089429268495, 7623.656935437919, 7669.271419215165, 7680.423968202379, 7687.115981662097, 7658.234176582325, 7696.884546731803, 7722.420818438612, 7723.7682869514, 7740.109887362969, 7624.199459056802, 7679.57762323072, 7692.153863075057, 7700.0, 7700.921870938045, 7529.887051694229, 7552.66013029415, 7565.243475652391, 7584.848303033908, 7598.468107232496, 36741.63258367409, 36874.262514749826, 36949.81525092384, 37137.44293835595, 37150.62849371499, 2880.7983441158917, 3126.89055883043, 3163.8418079095954, 3172.333809899924, 3253.072874230833, 29221.0, 29522.11433656994, 29574.0, 29812.552811707843, 29971.550426743615, 3186.1078897908624, 3190.2502911815704, 3221.468457704476, 3258.4786434170655, 3328.284418849935, 268714.0, 271972.0, 273258.0, 273702.0, 273806.0, 31223.219419514695, 31291.217719557193, 53033.0, 53446.0, 53452.0, 32975.175620609676, 33012.339753204804, 33186.66813331884, 33213.335733285196, 33319.333613327595, 12557.309347985949, 12689.619311420673, 12700.237985720887, 12745.49018039268, 12761.106722529334, 10292.794144117226, 10368.0, 10374.22193335503, 10375.16998640107, 10392.220583456272, 7743.419243556756, 7764.184760600056, 7800.375969922393, 7808.960955195188, 7812.6093695315, 2974.509205981032, 2994.4609970205574, 3009.307859192398, 3023.591815104977, 3043.269615292314, 846.5301757524633, 887.9334049946151, 902.0393281155548, 934.621478301289, 1018.7300365403025, 27669.0, 27933.16200513988, 27960.86019569889, 28261.29346766306, 28484.8575757124, 895.901450840407, 908.2370808520742, 924.7364501117149, 929.809389075238, 978.0, 13141.934290328678, 13860.445582176802, 14057.578272651837, 14136.575902722729, 14229.0, 392847.0, 393392.0, 394506.0, 394521.0, 396595.0, 46943.29585056227, 47303.526964729914, 47373.289400659014, 47464.7626761864, 47517.28724069142, 1168.094726586902, 1177.711460692136, 1206.2943178240653, 1215.3376409856648, 1220.700928272579, 25372.0, 26199.60700589531, 26786.0, 26875.73124268732, 27007.1897843065, 45768.54231457776, 47264.0, 47337.52662473331, 51662.225066624786, 51771.0, 366227.0, 367453.0, 369444.0, 370682.0, 372617.0, 37605.61549460562, 38333.54998050189, 38372.61627383802, 38535.0, 38973.220535589695, 41910.0, 42146.36780448235, 42505.36241956311, 42574.36138457864, 43563.56436435595, 35065.47401788924, 36271.274574507835, 36409.27181456409, 36472.452913205794, 37618.0, 33895.49156762601, 36689.63310366969, 37294.0, 37460.812695937435, 38120.23759524739, 34306.0, 34566.82716586401, 34876.12809679778, 35196.47205291974, 35272.294554109285, 31130.844345778125, 33691.15772105717, 33739.15652108717, 33753.662463375054, 33808.49287260742, 24182.0, 24355.39111522226, 24554.386140346633, 24801.25596232116, 24818.503629926938], "value": 16470.622479303103, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8412214503474, 365.75859932444575, 366.1578369749576, 372.6013165912475, 377.9455319658154, 15542.766858497132, 15934.760978585316, 16667.666646667076, 17228.310867565284, 17306.221220045096, 14816.6295842604, 15801.209939503036, 15858.524244272661, 15963.840361596409, 15977.680446391094, 5010.72441015744, 5177.223416487538, 5205.115130427817, 5208.921866172011, 5211.635185537014, 4977.303177555127, 5126.461721519243, 5133.486651334874, 5157.974210128944, 5168.974155129219, 4999.150144475448, 5040.722760248185, 5052.26742122392, 5076.720780357079, 5149.0474262261605, 5083.979103394462, 5116.258142569325, 5128.743562821863, 5129.230615407719, 5141.408737995141, 4567.200739870509, 4592.816287348502, 4597.862064138081, 4602.332661764025, 4610.861674149781, 20755.377338679864, 21290.893545532173, 21335.253266135765, 21520.677189842165, 21630.24294149713, 2153.2786516516985, 2195.838627700126, 2425.211806163004, 2527.184807194517, 2536.467341858212, 13118.803217951738, 13641.931790341085, 13769.0, 13901.304934753323, 14229.430822767077, 1761.6828970785255, 1771.5571107223195, 1776.2717285912713, 1812.0577299804029, 1862.9720554191697, 69891.65054174747, 69914.65042674754, 69965.0, 70761.64619176922, 71026.28973710249, 8499.36519678889, 8584.957075214585, 8928.285737141014, 9761.828580570234, 9958.352707074017, 13218.140820846615, 13271.469141234242, 13286.269255190864, 13328.933355333162, 13355.799663005062, 6933.791986240421, 6970.651467426608, 7116.0, 7233.45749068822, 7241.818954526179, 6636.701348439332, 6711.832204194837, 6723.899141512881, 6923.169219693669, 6924.861502769916, 6044.909326360108, 6046.576739628272, 6048.667323297261, 6103.633781973096, 6290.339514351019, 1721.7675613792228, 1759.8504127149079, 1763.7795275590552, 1771.60138968731, 1779.4305822136791, 855.2901092093548, 858.9613467393982, 868.1665601022962, 918.8943271523726, 953.8903026152082, 17298.865182482674, 18127.63744725098, 18171.819381674282, 18207.63584728298, 18341.0, 860.617025423693, 871.6513394642067, 878.6090189865448, 897.9012308646044, 900.2213085680839, 9925.60297588103, 10334.191516484585, 10425.270231083752, 10515.52680129396, 10539.367637941748, 385539.0, 388450.0, 391719.0, 391787.0, 393901.0, 38899.416508752394, 47190.0, 52553.0, 67798.66100669465, 73491.0, 898.5641963647626, 913.8172365526921, 927.4806108579218, 939.9765005874908, 946.1059298962552, 16001.439949601709, 16455.341786328412, 16587.502374928772, 16902.0, 17197.65604687924, 26036.479270414107, 34485.827570861984, 35933.820330898176, 36285.455718164776, 36823.63176368202, 49109.0, 49961.25058124059, 51159.48840511547, 59298.0, 59337.0, 7667.539947603162, 7856.646450909609, 7874.291313781787, 8053.758387248392, 8076.0, 9669.516524173623, 11564.710882228012, 11775.17573769862, 11963.940180299043, 11965.401729913643, 9613.951930240304, 9896.802063958536, 9946.0, 9989.0, 10250.641227557, 10333.74165645865, 10404.375737455777, 10457.581696732046, 10593.89406105929, 10927.890721092688, 9883.11052005323, 9886.159676427584, 9924.206063515046, 9948.651797187063, 10058.54736536843, 7828.804279893048, 7839.882401764092, 7903.604819759104, 7950.681972721027, 8161.428699990944, 4875.341828853201, 8635.438696484709, 9384.483853388128, 9665.178459830711, 10138.138258248131], "value": 8241.570940683756, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.6227675112589, 1336.6591519162612, 1352.6618345413647, 1611.8952268102573, 1619.2227730689262, 6370.5540612157165, 7164.104486939133, 7388.778336649909, 7508.549487030782, 7558.848823023536, 7429.145648250453, 7613.162552119263, 7616.314531692146, 7629.656665450054, 7680.231976802317, 2101.7267755191806, 2132.2323963373174, 2133.242698841912, 2135.1139277200023, 2145.5279838435517, 2079.6360636888558, 2095.6332641787703, 2146.4633841539617, 2146.7209262795814, 2149.022194901324, 2134.285014520138, 2137.551114266006, 2150.9892450537805, 2151.515908920496, 2158.6006588781047, 2090.2788537954534, 2095.0, 2099.6220680277543, 2102.8633138845926, 2105.336818902041, 2068.979310206909, 2115.5663089066707, 2119.682047692843, 2134.5090629155225, 2134.9893250533646, 7470.58911759853, 7541.0, 7550.6979720811105, 7559.886601700979, 7578.166401695809, 4262.424572682679, 4397.1205758848355, 4399.780010999437, 4400.251957167289, 4401.163778882034, 2749.917502474929, 2800.929976750577, 2818.788590855694, 2848.1170837040586, 2889.942201155965, 870.1907226279558, 891.3092353426082, 898.6225785170244, 900.5047224026824, 902.1564836877541, 10382.84425733607, 10411.791764164751, 10443.68668939933, 10447.895521044768, 10449.425281609509, 3829.1384438501673, 4002.699797515198, 4076.5923407658997, 4098.549159592442, 4145.0880806222585, 8905.73282801517, 8907.688230911886, 8935.687250946186, 8938.329625278131, 8944.418612790148, 4340.066885619607, 4376.737395756265, 4385.868423947287, 4409.625181859577, 4419.315006174048, 3315.0, 3956.1296514766705, 4058.797060146981, 4064.4716186895516, 4065.7763822989427, 3532.3111993161087, 3571.3035957987945, 3610.3140403323287, 3642.581103173169, 3671.761335513183, 1136.9540023178695, 1142.5429828068839, 1149.230015889356, 1162.511745067074, 1166.2419427372276, 591.9052951527735, 613.8588124731336, 686.1800148822109, 692.8926016467456, 707.9787606371817, 4775.6657034007985, 4838.806447742121, 4978.054169707744, 4979.103761322925, 5178.896422071537, 843.4306842881153, 863.3524856357732, 913.9398297974318, 929.2101713543569, 932.8991789688233, 3286.2277364819247, 3319.8174100424226, 3360.8655653774063, 3431.296584200233, 3452.7065199457825, 9105.135012173832, 9298.90701092994, 9554.378965367228, 9558.0, 9565.90434095664, 9322.20761235289, 9338.906610933804, 9339.252859771203, 9342.112499312554, 9375.484348360906, 7082.752103676449, 7119.679614417364, 7221.855562888818, 7288.8177795554475, 7316.561006339637, 4951.653384263067, 4978.751062446936, 4988.850334489971, 5021.322121513549, 5104.489551044866, 8661.653533858575, 8720.12798720123, 8751.431156974777, 8796.384253102222, 8840.939087309564, 8830.514321712368, 8874.556272186494, 8993.640254389751, 8996.59515321799, 9053.366264361432, 5504.779808807763, 6198.256209254918, 6220.906686399617, 6341.985282354801, 6380.553361264667, 7008.22909479967, 7026.332498412643, 7039.9296007039275, 7044.36600705939, 7062.752803651848, 6233.283172435046, 6412.0, 6441.452476539546, 6603.306652801387, 6609.339066093352, 6111.938880611137, 6330.683465826783, 6387.8722425550295, 6449.0, 6469.320721324381, 5843.590948633555, 5909.6158749681135, 5911.497522710618, 5914.497267732291, 5941.37615550361, 4350.782460877007, 4398.626116779981, 4426.336049592587, 4481.955180448284, 4520.141173177086, 4314.460692413449, 4367.235733746518, 4529.954700452953, 4562.0, 4586.977065114653], "value": 3976.997911840814, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.5961695923951, 282.3660881321433, 284.49644129890083, 286.999805677215, 292.53340921230637, 4905.754712264382, 5033.245013248014, 5037.924431133536, 5101.209312556551, 5145.041601079376, 5037.445880953102, 5048.3437153170125, 5061.873453163673, 5063.911259079297, 5074.746262686869, 3976.6421022108007, 3980.4029395590605, 3981.6814654827544, 3983.6414722674954, 3989.68082553395, 3862.2854771867155, 3947.8748556661358, 3985.760854348734, 3988.461557689718, 3991.0421498840215, 3896.629820167094, 3899.1032062625613, 3909.2963266612, 3911.7261791674596, 3932.6853851691794, 3891.665000999392, 3899.2396482685886, 3919.667313113555, 3930.155016671429, 3982.26328129297, 3209.3741720364537, 3336.96663033369, 3340.2484441000825, 3460.083077984311, 3462.515247865301, 4874.878128046792, 4913.248580794766, 4913.582345500637, 4916.532929371704, 4949.925751113737, 4375.803088860977, 4385.2106620808245, 4410.713303635285, 4432.429191069172, 4442.311441726538, 5017.799288028475, 5209.244659524347, 5222.268882356473, 5284.180951952453, 5292.629515933886, 3624.836882340301, 3636.218213084173, 3638.7810083622016, 3642.963570364289, 3651.8721844735574, 5498.532624726903, 5516.889662206733, 5535.225068490415, 5542.168674698787, 5586.86032849178, 3689.1330537323706, 3752.1932784451474, 3785.072657198949, 3791.4123310886857, 3792.3742582473583, 3780.6597406233573, 3803.9429408558894, 3807.371783655721, 3816.9427458588143, 3836.961630383716, 4374.212641724456, 4399.824007039747, 4420.138073075784, 4428.962677168474, 4440.289553671397, 5298.8207075754235, 5315.309009828775, 5330.0, 5355.250264962909, 5380.946190538045, 1272.2239433945285, 1295.1322613848745, 1317.0, 1322.1207896748747, 1327.6548097494722, 814.9877751833727, 822.4160845799549, 826.6114925984778, 833.5415521463292, 836.6611522333465, 4137.731047481964, 4140.792960351971, 4168.8124034417915, 4174.269502836992, 4185.330347144442, 4752.809887604457, 4756.833510827174, 4779.777403507385, 4829.66192366538, 4864.513548645107, 4064.7154699170774, 4079.3897400800506, 4087.7547347159266, 4160.91678166441, 4163.584381310308, 2591.8704064796684, 2683.4901368740266, 2899.0, 2930.369970456362, 2936.4420760055523, 5135.820246291437, 5158.92261616076, 5160.690358578422, 5166.9224961625605, 5168.508991645787, 5398.406175320712, 5619.494245517923, 5647.576431767634, 5650.174912543728, 5650.208970744099, 5092.719900405439, 5108.28484012246, 5108.335916330928, 5120.876265478056, 5129.0, 5486.561075114061, 5493.599132221258, 5525.806596769094, 5533.529649979797, 5546.417626149197, 5099.69401835891, 5375.5162035417, 5418.918716219338, 5423.2407462954425, 5423.701696406735, 5137.09753988995, 5144.459831717616, 5152.845414637567, 5153.484651534816, 5160.380754309357, 3510.754247202671, 3652.379095553815, 3733.197362567061, 3796.7911764853197, 3805.2389522095136, 5412.485813847678, 5420.783168673209, 5432.44675002748, 5435.891282174255, 5473.589480788877, 5188.728761453468, 5274.8945021100135, 5298.54962328191, 5308.522232999048, 5314.867128321823, 5126.948975460096, 5215.921761173661, 5253.605979551472, 5282.418933917189, 5297.913927644747, 5185.651730550008, 5191.896162076813, 5206.0, 5207.92188117171, 5213.661112027706, 5279.260903473593, 5304.761285742228, 5305.469453054664, 5316.574674026146, 5416.889537644852, 4984.4517103117905, 5225.973870130625, 5233.371995360581, 5288.550473209821, 5306.363236411656], "value": 3907.2414286012327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.9309900064493, 476.9618430525558, 652.6540933305348, 679.2629996453848, 714.2464699741773, 11261.66215013549, 12494.56269030583, 13850.0, 13916.930415347922, 14575.198364089998, 13674.0, 14781.039232449883, 14825.110493370405, 14852.18312992785, 14909.40362385506, 732.527519749761, 757.3713817531436, 764.9541027538365, 766.337118392592, 767.1139833492325, 750.8423231121471, 753.0511555440157, 755.8526087412956, 755.8752805787038, 757.4773406349628, 803.7468197517763, 804.891339669146, 805.899262592176, 806.7620052084649, 807.0557447786105, 770.2143813310392, 800.0878997942292, 801.3589128697048, 801.707376807464, 803.7267329108131, 565.3893794701753, 568.0938902450584, 572.5791543215734, 573.2891214893543, 574.2792795042237, 12802.48790048397, 12850.614481565568, 12888.420021099073, 12921.160124591965, 13016.219026858418, 13022.153560018663, 13255.0, 13703.520376786764, 13747.518836840763, 14034.087784293988, 10042.246831487595, 10285.537150828231, 10335.379877207391, 10345.844812327821, 10426.530806113744, 3223.5003574446, 3253.316803471274, 3294.3081952789944, 3295.472724364114, 3345.1302661308237, 65236.34763652398, 65687.34312656907, 65842.34157658363, 66583.66708166477, 66634.33365666379, 9322.25421966241, 9468.100530449596, 9659.32384733062, 9782.070703283176, 9814.263930205263, 19024.80975190258, 19113.33103341404, 19152.90423547901, 19162.425127246206, 19428.0, 6617.073609694647, 6641.435477984329, 6645.867082658319, 6726.764563240263, 6775.898361524581, 3666.8533258669354, 3694.9815250923575, 3723.0, 3726.5900750917376, 3740.345439548068, 2901.8113822601463, 3007.0, 3008.0524634739986, 3009.9247518811767, 3188.3623275345026, 522.4357693690805, 524.8268071536384, 531.0759278854765, 532.8374845672107, 535.807109440601, 437.85331913809233, 460.86146405280766, 497.42795784846993, 530.1888111189886, 544.5725105791935, 7647.0, 7837.787871065678, 8425.283850872622, 8437.0, 8554.27288680469, 452.75098695717224, 473.30187972740623, 498.4492136189531, 526.5632346027207, 528.7250629672549, 3823.235352929425, 3843.8462461501226, 3901.51231096113, 3906.628870257321, 4018.718689691752, 104589.47705261425, 105999.47000265104, 109878.45060774645, 111410.0, 111780.44109779398, 92431.0, 94591.0, 97179.0, 98923.50538247262, 99055.0, 87204.0, 87766.0, 88143.55928220446, 89377.0, 90023.0, 7323.780286591411, 7425.331720145213, 7450.143233527997, 7533.246675332643, 7610.277023682741, 46614.30078548757, 47040.529594703614, 47978.28032579444, 48763.367480089335, 49342.0, 66869.0, 72107.27892721216, 72547.0, 72787.63606181936, 74022.0, 9804.803903921738, 9897.455639939872, 9939.801203976025, 10001.799964000826, 10021.298509104292, 11239.10087193039, 11397.715057123638, 12149.817752733892, 12434.316112613893, 12522.186057906207, 11623.360715160747, 11626.941865290619, 11825.40872956366, 12023.879761202626, 12313.507459701512, 11294.60468883585, 11492.655220343404, 11534.192606517463, 11724.531018759495, 12121.515139394678, 11895.167338286237, 11977.281363117892, 12121.333326667118, 12280.938595306967, 12493.312867792358, 9353.859692104488, 9556.522173891417, 9827.606895724091, 9978.700638980843, 10227.94886025565, 9345.579448924678, 10081.193504519768, 10312.329698569569, 10485.633002844865, 10531.789364212826], "value": 6560.893324336265, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1586.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 110.0, 114.0, 114.0, 114.0, 116.0, 114.0, 115.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 122.0, 152.0, 186.0, 127.0, 146.0, 158.0, 124.0, 205.0, 125.0], "value": 151.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 166.0, 175.0, 165.0, 163.0, 163.0, 166.0, 164.0, 162.0], "value": 165.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 185.0, 76.0, 76.0, 76.0, 77.0, 165.0, 78.0], "value": 96.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 161.0, 85.0, 86.0, 86.0, 134.0, 85.0, 84.0, 144.0], "value": 103.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0, 123.0], "value": 118.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 150.0, 148.0, 147.0, 148.0, 148.0, 149.0, 149.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0, 96.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 64.0, 66.0, 65.0, 66.0, 64.0, 64.0, 64.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 67.0, 66.0, 65.0, 65.0, 65.0, 65.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 81.0, 87.0, 83.0, 86.0, 80.0, 79.0, 79.0, 84.0, 80.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 143.0, 136.0, 142.0, 134.0, 144.0, 148.0, 164.0, 132.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 166.0, 174.0, 175.0, 174.0, 168.0, 168.0, 166.0, 166.0, 165.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 68.0, 68.0, 68.0, 70.0, 67.0, 67.0, 68.0, 68.0], "value": 68.5, "unit": "score"}]}]} 09:49:21 INFO - Return code: 0 09:49:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:49:21 ERROR - # TBPL SUCCESS # 09:49:21 INFO - Running post-action listener: _resource_record_post_action 09:49:21 INFO - [mozharness: 2016-08-08 16:49:21.248674Z] Finished run-tests step (success) 09:49:21 INFO - Running post-run listener: _resource_record_post_run 09:49:21 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 537067520; Read time: 12; Write time: 524844 09:49:21 INFO - TinderboxPrint: CPU usage
13.3% 09:49:21 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 09:49:21 INFO - TinderboxPrint: I/O write bytes / time
537,067,520 / 524,844 09:49:21 INFO - TinderboxPrint: CPU idle
8,283.2 (86.6%) 09:49:21 INFO - TinderboxPrint: CPU user
1,219.9 (12.8%) 09:49:21 INFO - TinderboxPrint: Swap in / out
0 / 0 09:49:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 250466304; Read time: 0; Write time: 301012 09:49:21 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 286601216; Read time: 12; Write time: 223832 09:49:21 INFO - Running post-run listener: _upload_blobber_files 09:49:21 INFO - Blob upload gear active. 09:49:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:49:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:49:21 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:49:21 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:49:22 INFO - (blobuploader) - INFO - Open directory for files ... 09:49:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:49:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:49:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:23 INFO - (blobuploader) - INFO - Done attempting. 09:49:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:49:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:49:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:27 INFO - (blobuploader) - INFO - Done attempting. 09:49:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:49:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:49:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:49:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:49:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:49:28 INFO - (blobuploader) - INFO - Done attempting. 09:49:28 INFO - (blobuploader) - INFO - Iteration through files over. 09:49:28 INFO - Return code: 0 09:49:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:49:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:49:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c2fa0e6d0417c1a1ea4899ed1c1bb336cfcecb659b3b156ac6374ae004687cdf596ed2341e57ba6bb6f186bb6227a3b57601b8b228e1d139d3c9fbda2c4eae8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fea8b3c962483d838700df9ef76b2663f561c1fea2e3cf3fdc8230c904026f80caaf1a7458a89479e6c9a51956913178a04b4ec485ea61e574cfde2aea1ef440", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6f60d17851909c785844a0998c07fc7e1407a2b55d0b39a5450c153cb2a77a7a733853249abcfa749c81f0249364bac43004030722f49dc550fe9819fd8014dd"} 09:49:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:49:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:49:28 INFO - Contents: 09:49:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c2fa0e6d0417c1a1ea4899ed1c1bb336cfcecb659b3b156ac6374ae004687cdf596ed2341e57ba6bb6f186bb6227a3b57601b8b228e1d139d3c9fbda2c4eae8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fea8b3c962483d838700df9ef76b2663f561c1fea2e3cf3fdc8230c904026f80caaf1a7458a89479e6c9a51956913178a04b4ec485ea61e574cfde2aea1ef440", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6f60d17851909c785844a0998c07fc7e1407a2b55d0b39a5450c153cb2a77a7a733853249abcfa749c81f0249364bac43004030722f49dc550fe9819fd8014dd"} 09:49:28 INFO - Running post-run listener: copy_logs_to_upload_dir 09:49:28 INFO - Copying logs to upload dir... 09:49:28 INFO - mkdir: /builds/slave/test/build/upload/logs 09:49:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.307056 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-08-08 09:49:28.397172) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 09:49:28.400689) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c2fa0e6d0417c1a1ea4899ed1c1bb336cfcecb659b3b156ac6374ae004687cdf596ed2341e57ba6bb6f186bb6227a3b57601b8b228e1d139d3c9fbda2c4eae8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fea8b3c962483d838700df9ef76b2663f561c1fea2e3cf3fdc8230c904026f80caaf1a7458a89479e6c9a51956913178a04b4ec485ea61e574cfde2aea1ef440", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6f60d17851909c785844a0998c07fc7e1407a2b55d0b39a5450c153cb2a77a7a733853249abcfa749c81f0249364bac43004030722f49dc550fe9819fd8014dd"} build_url:https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011344 build_url: 'https://queue.taskcluster.net/v1/task/Y4yBo4YaRqiST94tUxGAuQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c2fa0e6d0417c1a1ea4899ed1c1bb336cfcecb659b3b156ac6374ae004687cdf596ed2341e57ba6bb6f186bb6227a3b57601b8b228e1d139d3c9fbda2c4eae8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fea8b3c962483d838700df9ef76b2663f561c1fea2e3cf3fdc8230c904026f80caaf1a7458a89479e6c9a51956913178a04b4ec485ea61e574cfde2aea1ef440", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6f60d17851909c785844a0998c07fc7e1407a2b55d0b39a5450c153cb2a77a7a733853249abcfa749c81f0249364bac43004030722f49dc550fe9819fd8014dd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 09:49:28.426093) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:49:28.426423) ========= 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=e31afd3c0f0de4e86ebe4d94000001b5-1470672866.79365-966720012 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004572 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 09:49:28.507764) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 09:49:28.508061) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 09:49:28.508338) ========= ========= Total master_lag: 0.21 =========