builder: autoland_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-015 starttime: 1468991175.69 results: success (0) buildid: 20160719183002 builduid: f80f74e198f34282a5bab7702b0db241 revision: 4dd2466573eceb92c70f8436ad54bb400ae4110b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.693218) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.693683) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.693954) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.708375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.708692) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 22:06:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 65.3M=0s 2016-07-19 22:06:15 (65.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085813 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.808094) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.808419) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017848 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.859099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:15.859478) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4dd2466573eceb92c70f8436ad54bb400ae4110b --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 4dd2466573eceb92c70f8436ad54bb400ae4110b --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 22:06:15,950 truncating revision to first 12 chars 2016-07-19 22:06:15,950 Setting DEBUG logging. 2016-07-19 22:06:15,951 attempt 1/10 2016-07-19 22:06:15,951 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4dd2466573ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 22:06:16,260 unpacking tar archive at: autoland-4dd2466573ec/testing/mozharness/ program finished with exit code 0 elapsedTime=0.638123 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:16.510954) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:16.511272) ========= script_repo_revision: 4dd2466573eceb92c70f8436ad54bb400ae4110b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:16.511662) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:16.511945) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 22:06:16.520522) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-19 22:06:16.520803) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:06:16 INFO - MultiFileLogger online at 20160719 22:06:16 in /builds/slave/test 22:06:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:06:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:06:16 INFO - {'append_to_log': False, 22:06:16 INFO - 'base_work_dir': '/builds/slave/test', 22:06:16 INFO - 'blob_upload_branch': 'Autoland', 22:06:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:06:16 INFO - 'branch': 'Autoland', 22:06:16 INFO - 'buildbot_json_path': 'buildprops.json', 22:06:16 INFO - 'config_files': ('talos/linux_config.py',), 22:06:16 INFO - 'default_actions': ('clobber', 22:06:16 INFO - 'read-buildbot-config', 22:06:16 INFO - 'download-and-extract', 22:06:16 INFO - 'populate-webroot', 22:06:16 INFO - 'create-virtualenv', 22:06:16 INFO - 'install', 22:06:16 INFO - 'run-tests'), 22:06:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:06:16 INFO - 'download_minidump_stackwalk': True, 22:06:16 INFO - 'download_symbols': 'ondemand', 22:06:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:06:16 INFO - 'tooltool.py': '/tools/tooltool.py', 22:06:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:06:16 INFO - '/tools/misc-python/virtualenv.py')}, 22:06:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:06:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:06:16 INFO - 'installer_path': 'installer.exe', 22:06:16 INFO - 'log_level': 'info', 22:06:16 INFO - 'log_name': 'talos', 22:06:16 INFO - 'log_to_console': True, 22:06:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:06:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:06:16 INFO - 'opt_config_files': (), 22:06:16 INFO - 'pip_index': False, 22:06:16 INFO - 'sps_profile': False, 22:06:16 INFO - 'sps_profile_interval': 0, 22:06:16 INFO - 'suite': 'dromaeojs-e10s', 22:06:16 INFO - 'system_bits': '32', 22:06:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:06:16 INFO - 'title': 'talos-linux64-ix-015', 22:06:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:06:16 INFO - 'use_talos_json': True, 22:06:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:06:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:06:16 INFO - 'work_dir': 'build'} 22:06:16 INFO - [mozharness: 2016-07-20 05:06:16.706581Z] Running clobber step. 22:06:16 INFO - Running pre-action listener: _resource_record_pre_action 22:06:16 INFO - Running main action method: clobber 22:06:16 INFO - rmtree: /builds/slave/test/build 22:06:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:06:17 INFO - Running post-action listener: _resource_record_post_action 22:06:17 INFO - [mozharness: 2016-07-20 05:06:17.234633Z] Finished clobber step (success) 22:06:17 INFO - [mozharness: 2016-07-20 05:06:17.234734Z] Running read-buildbot-config step. 22:06:17 INFO - Running pre-action listener: _resource_record_pre_action 22:06:17 INFO - Running main action method: read_buildbot_config 22:06:17 INFO - Using buildbot properties: 22:06:17 INFO - { 22:06:17 INFO - "project": "", 22:06:17 INFO - "product": "firefox", 22:06:17 INFO - "script_repo_revision": "production", 22:06:17 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs-e10s", 22:06:17 INFO - "repository": "", 22:06:17 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs-e10s", 22:06:17 INFO - "buildid": "20160719183002", 22:06:17 INFO - "pgo_build": "True", 22:06:17 INFO - "basedir": "/builds/slave/test", 22:06:17 INFO - "buildnumber": 46, 22:06:17 INFO - "slavename": "talos-linux64-ix-015", 22:06:17 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:06:17 INFO - "platform": "ubuntu64_hw", 22:06:17 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 22:06:17 INFO - "branch": "autoland", 22:06:17 INFO - "repo_path": "integration/autoland", 22:06:17 INFO - "revision": "4dd2466573eceb92c70f8436ad54bb400ae4110b", 22:06:17 INFO - "stage_platform": "linux64-pgo", 22:06:17 INFO - "builduid": "f80f74e198f34282a5bab7702b0db241", 22:06:17 INFO - "slavebuilddir": "test" 22:06:17 INFO - } 22:06:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:06:17 INFO - Running post-action listener: _resource_record_post_action 22:06:17 INFO - [mozharness: 2016-07-20 05:06:17.237150Z] Finished read-buildbot-config step (success) 22:06:17 INFO - [mozharness: 2016-07-20 05:06:17.237240Z] Running download-and-extract step. 22:06:17 INFO - Running pre-action listener: _resource_record_pre_action 22:06:17 INFO - Running main action method: download_and_extract 22:06:17 INFO - mkdir: /builds/slave/test/build/tests 22:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:17 INFO - https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:06:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:06:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:06:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:06:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:06:17 INFO - Downloaded 1361 bytes. 22:06:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:06:17 INFO - Using the following test package requirements: 22:06:17 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:06:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:06:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:06:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'jsshell-linux-x86_64.zip'], 22:06:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:06:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:06:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:06:17 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:06:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:06:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:06:17 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:06:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:17 INFO - https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:06:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:06:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:06:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:06:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:06:19 INFO - Downloaded 24335629 bytes. 22:06:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:06:20 INFO - Return code: 0 22:06:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:20 INFO - https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:06:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:06:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:06:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:06:21 INFO - Downloaded 24335629 bytes. 22:06:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:06:23 INFO - Return code: 0 22:06:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:23 INFO - https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:06:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:06:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:06:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:06:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:06:24 INFO - Downloaded 14403317 bytes. 22:06:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:06:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:06:24 INFO - Return code: 0 22:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:24 INFO - https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:06:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:06:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:06:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:06:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:06:25 INFO - Downloaded 60849832 bytes. 22:06:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:06:25 INFO - mkdir: /builds/slave/test/properties 22:06:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:06:25 INFO - Writing to file /builds/slave/test/properties/build_url 22:06:25 INFO - Contents: 22:06:25 INFO - build_url:https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:06:26 INFO - Running post-action listener: _resource_record_post_action 22:06:26 INFO - Running post-action listener: set_extra_try_arguments 22:06:26 INFO - [mozharness: 2016-07-20 05:06:26.532027Z] Finished download-and-extract step (success) 22:06:26 INFO - [mozharness: 2016-07-20 05:06:26.532263Z] Running populate-webroot step. 22:06:26 INFO - Running pre-action listener: _resource_record_pre_action 22:06:26 INFO - Running main action method: populate_webroot 22:06:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:06:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:06:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:06:26 INFO - '--tppagecycles', 22:06:26 INFO - '7'], 22:06:26 INFO - 'tests': ['tsvgm']}}, 22:06:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:06:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:06:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:06:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:06:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:06:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['damp', 'tps']}, 22:06:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'tests': ['damp', 'tps']}, 22:06:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['dromaeo_dom']}, 22:06:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:06:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['basic_compositor_video']}, 22:06:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:06:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['a11yr', 22:06:26 INFO - 'ts_paint', 22:06:26 INFO - 'tpaint', 22:06:26 INFO - 'sessionrestore', 22:06:26 INFO - 'sessionrestore_no_auto_restore', 22:06:26 INFO - 'tabpaint']}, 22:06:26 INFO - 'other-e10s': {'tests': ['a11yr', 22:06:26 INFO - 'ts_paint', 22:06:26 INFO - 'tpaint', 22:06:26 INFO - 'sessionrestore', 22:06:26 INFO - 'sessionrestore_no_auto_restore', 22:06:26 INFO - 'tabpaint']}, 22:06:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:06:26 INFO - 'ts_paint', 22:06:26 INFO - 'tpaint', 22:06:26 INFO - 'sessionrestore', 22:06:26 INFO - 'sessionrestore_no_auto_restore', 22:06:26 INFO - 'tabpaint']}, 22:06:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:06:26 INFO - 'ts_paint', 22:06:26 INFO - 'tpaint', 22:06:26 INFO - 'sessionrestore', 22:06:26 INFO - 'sessionrestore_no_auto_restore', 22:06:26 INFO - 'tabpaint']}, 22:06:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['a11yr', 22:06:26 INFO - 'ts_paint', 22:06:26 INFO - 'tpaint', 22:06:26 INFO - 'sessionrestore', 22:06:26 INFO - 'sessionrestore_no_auto_restore', 22:06:26 INFO - 'tabpaint']}, 22:06:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['a11yr', 22:06:26 INFO - 'ts_paint', 22:06:26 INFO - 'tpaint', 22:06:26 INFO - 'sessionrestore', 22:06:26 INFO - 'sessionrestore_no_auto_restore', 22:06:26 INFO - 'tabpaint']}, 22:06:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['tsvgx', 22:06:26 INFO - 'tsvgr_opacity', 22:06:26 INFO - 'tart', 22:06:26 INFO - 'tscrollx', 22:06:26 INFO - 'cart']}, 22:06:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:06:26 INFO - 'tsvgr_opacity', 22:06:26 INFO - 'tart', 22:06:26 INFO - 'tscrollx', 22:06:26 INFO - 'cart']}, 22:06:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'talos_options': ['--disable-e10s'], 22:06:26 INFO - 'tests': ['tp5o']}, 22:06:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'tests': ['tp5o']}, 22:06:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'talos_options': ['--disable-e10s', 22:06:26 INFO - '--xperf_path', 22:06:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:26 INFO - 'tests': ['tp5n']}, 22:06:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:06:26 INFO - 'talos_options': ['--xperf_path', 22:06:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:06:26 INFO - 'tests': ['tp5n']}}, 22:06:26 INFO - 'talos.zip': {'path': '', 22:06:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:06:26 INFO - Running post-action listener: _resource_record_post_action 22:06:26 INFO - [mozharness: 2016-07-20 05:06:26.554108Z] Finished populate-webroot step (success) 22:06:26 INFO - [mozharness: 2016-07-20 05:06:26.554198Z] Running create-virtualenv step. 22:06:26 INFO - Running pre-action listener: _resource_record_pre_action 22:06:26 INFO - Running main action method: create_virtualenv 22:06:26 INFO - Creating virtualenv /builds/slave/test/build/venv 22:06:26 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 22:06:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:06:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:06:26 INFO - Using real prefix '/usr' 22:06:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:06:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:06:29 INFO - Installing pip.................done. 22:06:29 INFO - Return code: 0 22:06:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:29 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 22:06:29 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 22:06:29 INFO - Using env: {'DISPLAY': ':0', 22:06:29 INFO - 'HOME': '/home/cltbld', 22:06:29 INFO - 'LANG': 'en_US.UTF-8', 22:06:29 INFO - 'LANGUAGE': 'en_US:en', 22:06:29 INFO - 'LOGNAME': 'cltbld', 22:06:29 INFO - 'MAIL': '/var/mail/cltbld', 22:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:29 INFO - 'MOZ_NO_REMOTE': '1', 22:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:29 INFO - 'NO_EM_RESTART': '1', 22:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:29 INFO - 'PWD': '/builds/slave/test', 22:06:29 INFO - 'SHELL': '/bin/bash', 22:06:29 INFO - 'SHLVL': '1', 22:06:29 INFO - 'TERM': 'linux', 22:06:29 INFO - 'TMOUT': '86400', 22:06:29 INFO - 'USER': 'cltbld', 22:06:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:29 INFO - '_': '/tools/buildbot/bin/python'} 22:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:29 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 22:06:29 INFO - Cleaning up... 22:06:29 INFO - Return code: 0 22:06:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:29 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:29 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 22:06:29 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 22:06:29 INFO - Using env: {'DISPLAY': ':0', 22:06:29 INFO - 'HOME': '/home/cltbld', 22:06:29 INFO - 'LANG': 'en_US.UTF-8', 22:06:29 INFO - 'LANGUAGE': 'en_US:en', 22:06:29 INFO - 'LOGNAME': 'cltbld', 22:06:29 INFO - 'MAIL': '/var/mail/cltbld', 22:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:29 INFO - 'MOZ_NO_REMOTE': '1', 22:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:29 INFO - 'NO_EM_RESTART': '1', 22:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:29 INFO - 'PWD': '/builds/slave/test', 22:06:29 INFO - 'SHELL': '/bin/bash', 22:06:29 INFO - 'SHLVL': '1', 22:06:29 INFO - 'TERM': 'linux', 22:06:29 INFO - 'TMOUT': '86400', 22:06:29 INFO - 'USER': 'cltbld', 22:06:29 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:29 INFO - '_': '/tools/buildbot/bin/python'} 22:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:29 INFO - Downloading/unpacking psutil>=3.1.1 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:06:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:31 INFO - Installing collected packages: psutil 22:06:31 INFO - Running setup.py install for psutil 22:06:31 INFO - building 'psutil._psutil_linux' extension 22:06:31 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 22:06:31 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 22:06:31 INFO - building 'psutil._psutil_posix' extension 22:06:31 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 22:06:32 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 22:06:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:06:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:06:32 INFO - Successfully installed psutil 22:06:32 INFO - Cleaning up... 22:06:32 INFO - Return code: 0 22:06:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:32 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:32 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 22:06:32 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 22:06:32 INFO - Using env: {'DISPLAY': ':0', 22:06:32 INFO - 'HOME': '/home/cltbld', 22:06:32 INFO - 'LANG': 'en_US.UTF-8', 22:06:32 INFO - 'LANGUAGE': 'en_US:en', 22:06:32 INFO - 'LOGNAME': 'cltbld', 22:06:32 INFO - 'MAIL': '/var/mail/cltbld', 22:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:32 INFO - 'MOZ_NO_REMOTE': '1', 22:06:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:32 INFO - 'NO_EM_RESTART': '1', 22:06:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:32 INFO - 'PWD': '/builds/slave/test', 22:06:32 INFO - 'SHELL': '/bin/bash', 22:06:32 INFO - 'SHLVL': '1', 22:06:32 INFO - 'TERM': 'linux', 22:06:32 INFO - 'TMOUT': '86400', 22:06:32 INFO - 'USER': 'cltbld', 22:06:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:32 INFO - '_': '/tools/buildbot/bin/python'} 22:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:06:32 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 22:06:32 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 22:06:32 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 22:06:32 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 22:06:32 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 22:06:32 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 22:06:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:06:34 INFO - Installing collected packages: mozsystemmonitor 22:06:34 INFO - Running setup.py install for mozsystemmonitor 22:06:34 INFO - Successfully installed mozsystemmonitor 22:06:34 INFO - Cleaning up... 22:06:34 INFO - Return code: 0 22:06:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:06:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:34 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:34 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 22:06:34 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 22:06:34 INFO - Using env: {'DISPLAY': ':0', 22:06:34 INFO - 'HOME': '/home/cltbld', 22:06:34 INFO - 'LANG': 'en_US.UTF-8', 22:06:34 INFO - 'LANGUAGE': 'en_US:en', 22:06:34 INFO - 'LOGNAME': 'cltbld', 22:06:34 INFO - 'MAIL': '/var/mail/cltbld', 22:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:34 INFO - 'MOZ_NO_REMOTE': '1', 22:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:34 INFO - 'NO_EM_RESTART': '1', 22:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:34 INFO - 'PWD': '/builds/slave/test', 22:06:34 INFO - 'SHELL': '/bin/bash', 22:06:34 INFO - 'SHLVL': '1', 22:06:34 INFO - 'TERM': 'linux', 22:06:34 INFO - 'TMOUT': '86400', 22:06:34 INFO - 'USER': 'cltbld', 22:06:34 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:34 INFO - '_': '/tools/buildbot/bin/python'} 22:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:34 INFO - Downloading/unpacking jsonschema==2.5.1 22:06:34 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 22:06:34 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 22:06:34 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 22:06:34 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 22:06:34 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 22:06:34 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 22:06:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:06:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:06:36 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 22:06:36 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 22:06:36 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 22:06:36 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 22:06:36 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 22:06:36 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 22:06:36 INFO - Downloading functools32-3.2.3-2.tar.gz 22:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:06:36 INFO - warning: no files found matching '*.txt' 22:06:36 INFO - no previously-included directories found matching 'build' 22:06:36 INFO - no previously-included directories found matching 'dist' 22:06:36 INFO - no previously-included directories found matching '.git*' 22:06:36 INFO - Installing collected packages: jsonschema, functools32 22:06:36 INFO - Running setup.py install for functools32 22:06:37 INFO - warning: no files found matching '*.txt' 22:06:37 INFO - no previously-included directories found matching 'build' 22:06:37 INFO - no previously-included directories found matching 'dist' 22:06:37 INFO - no previously-included directories found matching '.git*' 22:06:37 INFO - Successfully installed jsonschema functools32 22:06:37 INFO - Cleaning up... 22:06:37 INFO - Return code: 0 22:06:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:37 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:37 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 22:06:37 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 22:06:37 INFO - Using env: {'DISPLAY': ':0', 22:06:37 INFO - 'HOME': '/home/cltbld', 22:06:37 INFO - 'LANG': 'en_US.UTF-8', 22:06:37 INFO - 'LANGUAGE': 'en_US:en', 22:06:37 INFO - 'LOGNAME': 'cltbld', 22:06:37 INFO - 'MAIL': '/var/mail/cltbld', 22:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:37 INFO - 'MOZ_NO_REMOTE': '1', 22:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:37 INFO - 'NO_EM_RESTART': '1', 22:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:37 INFO - 'PWD': '/builds/slave/test', 22:06:37 INFO - 'SHELL': '/bin/bash', 22:06:37 INFO - 'SHLVL': '1', 22:06:37 INFO - 'TERM': 'linux', 22:06:37 INFO - 'TMOUT': '86400', 22:06:37 INFO - 'USER': 'cltbld', 22:06:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:37 INFO - '_': '/tools/buildbot/bin/python'} 22:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:37 INFO - Downloading/unpacking blobuploader==1.2.4 22:06:37 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 22:06:37 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 22:06:37 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 22:06:37 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 22:06:37 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 22:06:37 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 22:06:39 INFO - Downloading blobuploader-1.2.4.tar.gz 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:06:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:06:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 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 22:06:39 INFO - Downloading docopt-0.6.1.tar.gz 22:06:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:06:40 INFO - Installing collected packages: blobuploader, requests, docopt 22:06:40 INFO - Running setup.py install for blobuploader 22:06:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:06:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:06:40 INFO - Running setup.py install for requests 22:06:40 INFO - Running setup.py install for docopt 22:06:40 INFO - Successfully installed blobuploader requests docopt 22:06:40 INFO - Cleaning up... 22:06:40 INFO - Return code: 0 22:06:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:40 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:40 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 22:06:40 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 22:06:40 INFO - Using env: {'DISPLAY': ':0', 22:06:40 INFO - 'HOME': '/home/cltbld', 22:06:40 INFO - 'LANG': 'en_US.UTF-8', 22:06:40 INFO - 'LANGUAGE': 'en_US:en', 22:06:40 INFO - 'LOGNAME': 'cltbld', 22:06:40 INFO - 'MAIL': '/var/mail/cltbld', 22:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:40 INFO - 'MOZ_NO_REMOTE': '1', 22:06:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:40 INFO - 'NO_EM_RESTART': '1', 22:06:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:40 INFO - 'PWD': '/builds/slave/test', 22:06:40 INFO - 'SHELL': '/bin/bash', 22:06:40 INFO - 'SHLVL': '1', 22:06:40 INFO - 'TERM': 'linux', 22:06:40 INFO - 'TMOUT': '86400', 22:06:40 INFO - 'USER': 'cltbld', 22:06:40 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:40 INFO - '_': '/tools/buildbot/bin/python'} 22:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:40 INFO - Running setup.py (path:/tmp/pip-__t9yP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:40 INFO - Running setup.py (path:/tmp/pip-reRdR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:40 INFO - Running setup.py (path:/tmp/pip-YHq2tH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:41 INFO - Running setup.py (path:/tmp/pip-DBb1GZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:41 INFO - Running setup.py (path:/tmp/pip-gNXliO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:41 INFO - Running setup.py (path:/tmp/pip-pbC1zR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:41 INFO - Running setup.py (path:/tmp/pip-Fc3spi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:41 INFO - Running setup.py (path:/tmp/pip-sAnQwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:41 INFO - Running setup.py (path:/tmp/pip-fN_5Mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:41 INFO - Running setup.py (path:/tmp/pip-NzndGq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:41 INFO - Running setup.py (path:/tmp/pip-isPst6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:41 INFO - Running setup.py (path:/tmp/pip-yZHHAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:41 INFO - Running setup.py (path:/tmp/pip-HoINMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:41 INFO - Running setup.py (path:/tmp/pip-yYVy4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:41 INFO - Running setup.py (path:/tmp/pip-xn3OFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:41 INFO - Running setup.py (path:/tmp/pip-Mz5PGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:42 INFO - Running setup.py (path:/tmp/pip-d6lLC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:06:42 INFO - Running setup.py install for manifestparser 22:06:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Running setup.py install for mozcrash 22:06:42 INFO - Running setup.py install for mozdebug 22:06:42 INFO - Running setup.py install for mozdevice 22:06:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Running setup.py install for mozfile 22:06:42 INFO - Running setup.py install for mozhttpd 22:06:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Running setup.py install for mozinfo 22:06:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Running setup.py install for mozInstall 22:06:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:06:42 INFO - Running setup.py install for mozleak 22:06:42 INFO - Running setup.py install for mozlog 22:06:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for moznetwork 22:06:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozprocess 22:06:43 INFO - Running setup.py install for mozprofile 22:06:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozrunner 22:06:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Running setup.py install for mozscreenshot 22:06:43 INFO - Running setup.py install for moztest 22:06:43 INFO - Running setup.py install for mozversion 22:06:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:06:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:06:43 INFO - Cleaning up... 22:06:43 INFO - Return code: 0 22:06:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:43 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:43 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 22:06:43 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 22:06:43 INFO - Using env: {'DISPLAY': ':0', 22:06:43 INFO - 'HOME': '/home/cltbld', 22:06:43 INFO - 'LANG': 'en_US.UTF-8', 22:06:43 INFO - 'LANGUAGE': 'en_US:en', 22:06:43 INFO - 'LOGNAME': 'cltbld', 22:06:43 INFO - 'MAIL': '/var/mail/cltbld', 22:06:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:43 INFO - 'MOZ_NO_REMOTE': '1', 22:06:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:43 INFO - 'NO_EM_RESTART': '1', 22:06:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:43 INFO - 'PWD': '/builds/slave/test', 22:06:43 INFO - 'SHELL': '/bin/bash', 22:06:43 INFO - 'SHLVL': '1', 22:06:43 INFO - 'TERM': 'linux', 22:06:43 INFO - 'TMOUT': '86400', 22:06:43 INFO - 'USER': 'cltbld', 22:06:43 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:43 INFO - '_': '/tools/buildbot/bin/python'} 22:06:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:06:44 INFO - Running setup.py (path:/tmp/pip-Tk4o6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:06:44 INFO - Running setup.py (path:/tmp/pip-1ktzY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:06:44 INFO - Running setup.py (path:/tmp/pip-HZZq8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:06:44 INFO - Running setup.py (path:/tmp/pip-3bq57B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:06:44 INFO - Running setup.py (path:/tmp/pip-2yBARj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:06:44 INFO - Running setup.py (path:/tmp/pip-TpoPzc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:06:44 INFO - Running setup.py (path:/tmp/pip-j_cYhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:06:44 INFO - Running setup.py (path:/tmp/pip-1GqHi0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:06:44 INFO - Running setup.py (path:/tmp/pip-HoaLsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:06:44 INFO - Running setup.py (path:/tmp/pip-0xFXEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:06:44 INFO - Running setup.py (path:/tmp/pip-dmUNm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:06:44 INFO - Running setup.py (path:/tmp/pip-_PhzhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:06:44 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)) 22:06:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:06:44 INFO - Running setup.py (path:/tmp/pip-mywj2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:06:45 INFO - Running setup.py (path:/tmp/pip-btnDgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:45 INFO - Running setup.py (path:/tmp/pip-Kd1uxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:06:45 INFO - Running setup.py (path:/tmp/pip-NkkSwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:06:45 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)) 22:06:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:06:45 INFO - Running setup.py (path:/tmp/pip-TLjlDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:06:45 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)) 22:06:45 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)) 22:06:45 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)) 22:06:45 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)) 22:06:45 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)) 22:06:45 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)) 22:06:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:06:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:06:47 INFO - Downloading blessings-1.6.tar.gz 22:06:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:06:47 INFO - Installing collected packages: blessings 22:06:47 INFO - Running setup.py install for blessings 22:06:47 INFO - Successfully installed blessings 22:06:47 INFO - Cleaning up... 22:06:47 INFO - Return code: 0 22:06:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:06:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:47 INFO - Reading from file tmpfile_stdout 22:06:47 INFO - Current package versions: 22:06:47 INFO - argparse == 1.2.1 22:06:47 INFO - blessings == 1.6 22:06:47 INFO - blobuploader == 1.2.4 22:06:47 INFO - docopt == 0.6.1 22:06:47 INFO - functools32 == 3.2.3-2 22:06:47 INFO - jsonschema == 2.5.1 22:06:47 INFO - manifestparser == 1.1 22:06:47 INFO - mozInstall == 1.12 22:06:47 INFO - mozcrash == 0.17 22:06:47 INFO - mozdebug == 0.1 22:06:47 INFO - mozdevice == 0.48 22:06:47 INFO - mozfile == 1.2 22:06:47 INFO - mozhttpd == 0.7 22:06:47 INFO - mozinfo == 0.9 22:06:47 INFO - mozleak == 0.1 22:06:47 INFO - mozlog == 3.3 22:06:47 INFO - moznetwork == 0.27 22:06:47 INFO - mozprocess == 0.23 22:06:47 INFO - mozprofile == 0.28 22:06:47 INFO - mozrunner == 6.12 22:06:47 INFO - mozscreenshot == 0.1 22:06:47 INFO - mozsystemmonitor == 0.3 22:06:47 INFO - moztest == 0.7 22:06:47 INFO - mozversion == 1.4 22:06:47 INFO - psutil == 3.1.1 22:06:47 INFO - requests == 1.2.3 22:06:47 INFO - wsgiref == 0.1.2 22:06:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:47 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:47 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 22:06:47 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 22:06:47 INFO - Using env: {'DISPLAY': ':0', 22:06:47 INFO - 'HOME': '/home/cltbld', 22:06:47 INFO - 'LANG': 'en_US.UTF-8', 22:06:47 INFO - 'LANGUAGE': 'en_US:en', 22:06:47 INFO - 'LOGNAME': 'cltbld', 22:06:47 INFO - 'MAIL': '/var/mail/cltbld', 22:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:47 INFO - 'MOZ_NO_REMOTE': '1', 22:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:47 INFO - 'NO_EM_RESTART': '1', 22:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:47 INFO - 'PWD': '/builds/slave/test', 22:06:47 INFO - 'SHELL': '/bin/bash', 22:06:47 INFO - 'SHLVL': '1', 22:06:47 INFO - 'TERM': 'linux', 22:06:47 INFO - 'TMOUT': '86400', 22:06:47 INFO - 'USER': 'cltbld', 22:06:47 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:47 INFO - '_': '/tools/buildbot/bin/python'} 22:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 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)) 22:06:47 INFO - Cleaning up... 22:06:47 INFO - Return code: 0 22:06:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:06:47 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 0x1fd7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x213a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2298180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', '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 22:06:47 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 22:06:47 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 22:06:47 INFO - Using env: {'DISPLAY': ':0', 22:06:47 INFO - 'HOME': '/home/cltbld', 22:06:47 INFO - 'LANG': 'en_US.UTF-8', 22:06:47 INFO - 'LANGUAGE': 'en_US:en', 22:06:47 INFO - 'LOGNAME': 'cltbld', 22:06:47 INFO - 'MAIL': '/var/mail/cltbld', 22:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:06:47 INFO - 'MOZ_NO_REMOTE': '1', 22:06:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:06:47 INFO - 'NO_EM_RESTART': '1', 22:06:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:06:47 INFO - 'PWD': '/builds/slave/test', 22:06:47 INFO - 'SHELL': '/bin/bash', 22:06:47 INFO - 'SHLVL': '1', 22:06:47 INFO - 'TERM': 'linux', 22:06:47 INFO - 'TMOUT': '86400', 22:06:47 INFO - 'USER': 'cltbld', 22:06:47 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:06:47 INFO - '_': '/tools/buildbot/bin/python'} 22:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:06:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:06:48 INFO - Cleaning up... 22:06:48 INFO - Return code: 0 22:06:48 INFO - Running post-action listener: _resource_record_post_action 22:06:48 INFO - Running post-action listener: _start_resource_monitoring 22:06:48 INFO - Starting resource monitoring. 22:06:48 INFO - [mozharness: 2016-07-20 05:06:48.195129Z] Finished create-virtualenv step (success) 22:06:48 INFO - [mozharness: 2016-07-20 05:06:48.195608Z] Running install step. 22:06:48 INFO - Running pre-action listener: _resource_record_pre_action 22:06:48 INFO - Running main action method: install 22:06:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:06:48 INFO - Reading from file tmpfile_stdout 22:06:48 INFO - Detecting whether we're running mozinstall >=1.0... 22:06:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:06:48 INFO - Reading from file tmpfile_stdout 22:06:48 INFO - Output received: 22:06:48 INFO - Usage: mozinstall [options] installer 22:06:48 INFO - Options: 22:06:48 INFO - -h, --help show this help message and exit 22:06:48 INFO - -d DEST, --destination=DEST 22:06:48 INFO - Directory to install application into. [default: 22:06:48 INFO - "/builds/slave/test"] 22:06:48 INFO - --app=APP Application being installed. [default: firefox] 22:06:48 INFO - mkdir: /builds/slave/test/build/application 22:06:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:06:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:07:05 INFO - Reading from file tmpfile_stdout 22:07:05 INFO - Output received: 22:07:05 INFO - /builds/slave/test/build/application/firefox/firefox 22:07:05 INFO - Running post-action listener: _resource_record_post_action 22:07:05 INFO - [mozharness: 2016-07-20 05:07:05.743139Z] Finished install step (success) 22:07:05 INFO - [mozharness: 2016-07-20 05:07:05.743415Z] Running run-tests step. 22:07:05 INFO - Running pre-action listener: _resource_record_pre_action 22:07:05 INFO - Running main action method: run_tests 22:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:07:05 INFO - Python 2.7.3 22:07:05 INFO - Return code: 0 22:07:05 INFO - grabbing minidump binary from tooltool 22:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:07:05 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 0x22963b0>, '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 0x22972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2297740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:07:05 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 22:07:05 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 22:07:05 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 22:07:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:07:05 INFO - Return code: 0 22:07:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:07:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:07:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:07:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:07:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:07:05 INFO - Using env: {'DISPLAY': ':0', 22:07:05 INFO - 'HOME': '/home/cltbld', 22:07:05 INFO - 'LANG': 'en_US.UTF-8', 22:07:05 INFO - 'LANGUAGE': 'en_US:en', 22:07:05 INFO - 'LOGNAME': 'cltbld', 22:07:05 INFO - 'MAIL': '/var/mail/cltbld', 22:07:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:07:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:07:05 INFO - 'MOZ_NO_REMOTE': '1', 22:07:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:07:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:07:05 INFO - 'NO_EM_RESTART': '1', 22:07:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:07:05 INFO - 'PWD': '/builds/slave/test', 22:07:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:07:05 INFO - 'SHELL': '/bin/bash', 22:07:05 INFO - 'SHLVL': '1', 22:07:05 INFO - 'TERM': 'linux', 22:07:05 INFO - 'TMOUT': '86400', 22:07:05 INFO - 'USER': 'cltbld', 22:07:05 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157', 22:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:07:05 INFO - '_': '/tools/buildbot/bin/python'} 22:07:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:07:06 INFO - mozversion application_buildid: 20160719183002 22:07:06 INFO - mozversion application_changeset: 4dd2466573eceb92c70f8436ad54bb400ae4110b 22:07:06 INFO - mozversion application_display_name: Nightly 22:07:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:07:06 INFO - mozversion application_name: Firefox 22:07:06 INFO - mozversion application_remotingname: firefox 22:07:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:07:06 INFO - mozversion application_vendor: Mozilla 22:07:06 INFO - mozversion application_version: 50.0a1 22:07:06 INFO - mozversion platform_buildid: 20160719183002 22:07:06 INFO - mozversion platform_changeset: 4dd2466573eceb92c70f8436ad54bb400ae4110b 22:07:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:07:06 INFO - mozversion platform_version: 50.0a1 22:07:06 INFO - using testdate: 1468991226 22:07:06 INFO - actual date: 1468991226 22:07:06 INFO - starting webserver on 'localhost:53887' 22:07:06 INFO - SUITE-START | Running 2 tests 22:07:06 INFO - TEST-START | dromaeo_css 22:07:06 INFO - operating with platform_type : linux_ 22:07:06 INFO - Initialising browser for dromaeo_css test... 22:07:06 INFO - TEST-INFO | started process 6473 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5x0z5H/profile http://localhost:53887/getInfo.html) 22:07:10 INFO - PROCESS | 6473 | __metrics Screen width/height:1600/1200 22:07:10 INFO - PROCESS | 6473 | colorDepth:24 22:07:10 INFO - PROCESS | 6473 | Browser inner width/height: 1024/697 22:07:10 INFO - PROCESS | 6473 | __metrics 22:07:10 ERROR - PROCESS | 6473 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:07:11 INFO - TEST-INFO | 6473: exit 0 22:07:11 INFO - Browser initialized. 22:07:11 INFO - Running cycle 1/1 for dromaeo_css test... 22:07:11 INFO - TEST-INFO | started process 6597 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5x0z5H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:07:23 INFO - PROCESS | 6597 | RSS: Main: 168255488 22:07:23 INFO - PROCESS | 6597 | 22:08:13 INFO - PROCESS | 6597 | 1468991293001 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] 22:10:21 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:53887/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53887/tests/dromaeo/cssquery-ext.html) 22:10:22 INFO - PROCESS | 6597 | RSS: Main: 172044288 22:10:22 INFO - PROCESS | 6597 | 22:13:24 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:53887/tests/dromaeo/cssquery-ext.html (next: http://localhost:53887/tests/dromaeo/cssquery-jquery.html) 22:13:25 INFO - PROCESS | 6597 | RSS: Main: 169127936 22:13:25 INFO - PROCESS | 6597 | 22:16:27 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:53887/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53887/tests/dromaeo/cssquery-mootools.html) 22:16:27 INFO - PROCESS | 6597 | RSS: Main: 172994560 22:16:27 INFO - PROCESS | 6597 | 22:19:29 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:53887/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53887/tests/dromaeo/cssquery-prototype.html) 22:19:30 INFO - PROCESS | 6597 | RSS: Main: 166879232 22:19:30 INFO - PROCESS | 6597 | 22:22:32 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:53887/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53887/tests/dromaeo/cssquery-yui.html) 22:22:33 INFO - PROCESS | 6597 | RSS: Main: 169025536 22:22:33 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | Cycle 1(1): loaded http://localhost:53887/tests/dromaeo/cssquery-yui.html (next: http://localhost:53887/tests/dromaeo/cssquery-dojo.html) 22:25:36 INFO - PROCESS | 6597 | RSS: Main: 169639936 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | __start_tp_report 22:25:36 INFO - PROCESS | 6597 | _x_x_mozilla_page_load 22:25:36 INFO - PROCESS | 6597 | _x_x_mozilla_page_load_details 22:25:36 INFO - PROCESS | 6597 | |i|pagename|runs| 22:25:36 INFO - PROCESS | 6597 | |0;dojo.html;3101.3177101037772;3101.8449077546115;3115.33020400614;3117.9532307015397;3135.1378370947987;5475.753591088395;5513.145462453316;5522.585806064541;5522.806701765439;5525.364583072937;5444.7822087116565;5516.255305533752;5535.418781027995;5586.162075688649;5616.354119276284;4931.876703082425;4956.87607809804;4985.40175178979;4991.925121123186;5021.447640759514;4952.603791696655;5022.8949631081105;5041.294218809369;5062.038212739586;5094;4890.21756518957;4951.826686065989;4963.131451995886;4996.325496058022;5043.167877300241;4978.701277923336;5017.473165317626;5025.623078269108;5053.646244762868;5075.720835354054;4999.750012499397;5001.649884508086;5023.397192336906;5037.622178336639;5071.188609822448;5370.248165256867;5428.864278393033;5453.863653408657;5530.474604912527;5546.140348245988;5844.620099693507;5897.764089436418;5932.584719069667;5955.315138759055;5965.671888046156;5784.652920824764;5973.820785376446;5983.311919129313;6000.309964354112;6001.309849367335;5505.339359276913;5515.420880807537;5528.585356098229;5556.361018482886;5558.055130627803;6061.424164704346;6063.423974722395;6069.635821850703;6072.787452439144;6083.300420451616;5632.943670563324;5745.482906538432;5777.306723193244;5828.358880523139;5846.503047240947;5874.5887787854435;5917.644941303535;5917.733701983421;5923.940760592425;5959.374265702038;5831.737571809279;5878.559108066912;5888.146218798336;5894.115882617642;5920.674362909996;5674.858128546819;5843.561732869966;5850.502707269845;5928.170056192136;5931.288245410578;4425.35832304314;4502.482214545304;4512.819487220474;4530.07133537624;4551.294549344857;3994.021464741099;4030.5163380394542;4045.959540404617;4068.044009657728;4116.403121547419;5483.616146869761;5612.831615051555;5617.38208797032;5694.1458781183155;5719.399463056402;5946.970265148647;5982.252218472691;5987.790427335022;6032.457078862835;6044.063170208625;5554.361248456398;5607.80372686954;5636.351819540805;5640.379558248589;5656.490915817596;4925.359703238556;4984.675996060245;5114.181730923109;5114.28400023997;5140.383154021467;6178.907316390258;6181.072839074175;6190.721417536221;6198.845028874314;6199.504039676815;5980.940190598125;5998.700064996646;5999.4900433463645;6003.369646187088;6029;6017.06735456005;6086.908696369469;6116.366205733216;6139.9079013813935;6143.723532440961;5868.559858010581;5880.088586269135;5883.264591926009;5906.497947674324;5933.525317974638;5867.20792692998;5914.172015917689;5991.7902873399225;5992.8801423972145;5997.880042399041;5615.943840561706;5792.768289268551;5821.09772985188;5856.560757943256;5857.853553661193;5797.246357973437;5827.358990511125;5827.854303642443;5831.79588714393;5856.355800861987;5553.250311207935;5855.121731740273;5862.94137058624;5864.794732184524;5867.765289388377;5720.3707592164;5727.198192253171;5737.9139312911175;5755.942440575541;5774.769009239583;5487.917681234705;5598.636088654224;5618.438156184349;5635.182898479616;5656.547476201977 22:25:36 INFO - PROCESS | 6597 | |1;ext.html;566.0772940107627;592.3277080513617;603.7977277612;616.1004932798116;646.1535388640877;41696.791516042416;46103.30845037319;46125.30812037818;46219.53780462194;46319;46150;46341;46799.532004679946;46812;46830.297545536756;8888.866667;9027.277817774562;9048.728538143834;9073.863892041625;9084.772880678003;9066.501342426196;9144.131307525808;9153.588088536031;9159.954200229024;9162.037986011474;9130.04134565871;9150.130737579919;9156.908430915706;9184.08159184083;9195.862062069074;9069.773255668628;9078.86381704275;9134.680286189985;9186.816263674722;9197.632094716204;8857.734267971971;8904.643814247422;8950.194482496541;8997.415168014124;9026.638934442615;33764.15589610279;43228.78385608083;43441.045152967876;43578.564214358084;43697.12605747899;3464.6535346465403;3507.6141624421293;3658.5243918290716;3793.2223894101644;3817.0075780297043;33050.83474582612;33463.186404832304;33626.663733362846;34524.13689657779;34969.47545786815;4297.269464191095;4316.589804312602;4333.06839029607;4335.6965012449145;4347.69566130371;328269;329579;330882;331150;331464;35474.290514190085;35776.46335304921;36388.636113639055;36573.26853462916;36644.26711465756;37913.431298530544;37972;38013;38024.23951520954;38054.23891522154;15844.366225350857;15851.048937063813;15942.601434963986;15953.361865525481;15956.76064859028;13364.6658833528;13443.731125377437;13471.663208419672;13476.663083422993;13485.123466974812;8443.408961372768;8444.282236010007;8455.95772021136;8458.238758511641;8575.142485751376;3232.2565809863863;3277.9344413111603;3282.8030318180986;3321.6014078310272;3324.401607710587;1062.617457715222;1078.1482628723377;1094.584058057933;1105.6019832860163;1187.3825610682397;34083.65916340854;34163.14592135217;34176.82911585476;34215.65784342125;34749.47875781865;1158.5076342554548;1196.174639498743;1245.2217364147407;1277.0358379423476;1309.9148555343872;17228.483145505656;17831.127731588254;18000.189991450417;18036.028119507868;18112.184951677205;613108;615274;619102;619343;621102;62297.37702623006;63400.682996585645;63880.68059659672;63974;64001.67999159975;1354.1130559483554;1384.5154196031308;1398.028370282644;1401.3623801170443;1404.4382247101241;32430.675693242767;34065.59946490172;34237.315253695284;34486.82756586201;34826.651733482344;55607;55704.72147639236;55707;55892.72053639706;56209.43790562042;499198;502929;505559;506434;506976;26471.33821654474;27637.756300966103;27652.861735691193;29129;29414.82340706348;48571.27143092786;48743.75628121837;49273.753631231615;49910.50089499204;50094.74952625213;41396.379054313606;42285;42493.57506424896;42950.570494295906;43051.784741076095;41985.58014419939;42525.57474425216;42696.14607707766;42975;43062;40463;43899.561004390824;44920.32619510645;44957.775211125045;46112.53887461217;36191.638083618825;39970.200595987335;40277.39583906185;40559;40567.79716101401;29242.268943276587;29769.364817025038;30307.545386819656;30860;30957 22:25:36 INFO - PROCESS | 6597 | |2;jquery.html;382.8927900187948;386.3605732512689;393.12529621591955;395.83770654031844;414.37636357282287;14513.927430362834;19089.904550477262;19201.423957281288;20944.37166884996;20952.37142885714;19765.110570024346;19928;20040.699389509133;21121;21208.36374908751;5736.139579063132;5816.360200377955;5847.532197424217;5854.443827836349;5873.0603103503445;5697.430256974311;5770.682612456313;5771.797987070453;5814.970925145369;5838.445347691983;5612.382637909826;5697.572682048843;5717.142428635696;5752.395998420168;5874.236349274588;5671.489565939044;5723.112917497794;5723.570732195081;5726.31284245891;5757.971210143923;5073.934473760515;5133.332666753336;5164.793408263665;5189.040027594907;5202.739863006835;23989.7601023991;24152.51694966091;24349;24360.756392436026;24889.128880489276;2439.3657649011207;2469.814763892697;2545.6054311581734;2679.517686816372;3049.5273232648974;14664.853351466561;15435.845641543554;15457.690846183126;15524.61188470286;15720.528384148494;1896.9620607587908;1976.5849171673965;2026.9898650506652;2046.5395286060666;2059.938201853947;79390;81985.59007204926;83032.58483707604;83689.58155209245;84909;10929.540853307943;11271.718207044889;11303.660890173143;11306.773864522664;11314.490847911864;15456.768148477782;15527.767083493756;15554.22228888551;15566.688666226612;15649.843501565067;8277.213664701843;8466.492010479267;8583.828323433496;8714.738557843275;8736;8328.208820162075;8374.790630234293;8426.07313195548;8554.401191916506;8571.828563428697;4689.109069276827;4704;4782.856514304576;4843.200871856175;4890.559849613552;1982.0882394098587;1988.4233572263881;1999.9500012499805;2007.2773801431474;2038.9388318350473;917.3074328881652;966.7776411425411;1018.7758693087509;1079.643717573199;1081.8701755789355;18427.355042573447;20163.395098146786;20613.175472980914;20628.48428789262;20792.792072079086;998.2812375089924;1000.6047611193537;1033.7725700345911;1067.215596536545;1070.4594179939081;10340.37957722539;11452.312861228187;11497.482613282422;11549.653510394533;11560.364179970182;526030;534291;537078;537523;540440;90346.54826725824;99968.5001575002;105916.94083059071;106006.46996764967;106261;1008.4100801031332;1014.6144465103214;1034.8292531732332;1042.0309112525272;1063.9574417023232;18065.2773889043;18095.276188952295;18583.721244181077;18730.625387492448;18909;40590.79704601458;43960.4668843077;44257.55742442663;44608.77695611501;45062.549374505834;62093.37906620876;63260.683696581225;63898.361016389244;64885.351146487934;65591.34408655853;7300.415966722543;7754.263344982218;8623.698170564001;8644.654213831376;8647.178518040777;13363.665908352368;13662.590122296348;13957.720845583235;14029.579112626638;14145.858541414455;12405;12413.627591171919;12542.435590398272;12652.936735316265;12698.746025079261;11688.47401866935;11946.820797687868;12015.519379224734;12019.278843269432;12055.276683399024;11931.821022684493;12072.517099316281;12098.637040888787;12434.316112613531;12533.686657833627;7899.447038707235;7946.562939038177;7985.600719964094;8001.8399632008195;8172.060213075572;8993.730188094367;10666.306690065121;11644.53421863116;11678;11719.531218751154 22:25:36 INFO - PROCESS | 6597 | |3;mootools.html;1473.5505670770415;1516.067618414675;1652.4794689672751;1771.8848274862141;1784.6252287019718;7476.28975247352;8127.674893004273;8172.7548173554815;8703.129687031302;8722.476651400912;8228.465149765261;8406.159384061591;8442.873356899636;8444.957775211114;8481.448705834116;2234.9217777377794;2291.0950174680993;2293.3693234360603;2298.321995011467;2299.31020693792;2282.3609389370918;2316.143027079983;2322.477442575424;2330.9184178553755;2331.4404542909665;2265.909363625453;2269.716285464317;2286.5084006938505;2323.8838058097112;2326.488172602025;2211.8119959803435;2217.866927984326;2223.1440895255364;2225.5771403433296;2245.045855511401;2207.128184367182;2213.900374483152;2219.04581030157;2221.1781640792847;2228.097620463715;8492.617832197566;8544.102869198769;8547;8601.053884072748;8607.913920860774;4805.447373552051;5081.949180508222;5166.9224961625605;5176;5186.118359878829;3211.4701074322943;3254.1701866024014;3400.0819778659625;3431.0907609483506;3434.0899661589697;1119.44027986007;1129.6385156749843;1138.6868611131922;1148.8736239013701;1152.8904754048351;11633.767324653545;11701.18091733579;11737.53049878004;11737.94131029348;11740.236884602558;4332.740035597812;4434.246178149658;4546.113362954403;4561.178987782231;4566.840160594364;9893.554790034466;9955.104040636234;9960.601575937026;9963;9970.700878973643;5126.948730512647;5150.48495150482;5150.562202212854;5170.095233334151;5175.120229560908;4273.380359847804;4560.5895469407915;4678.274867395491;4751.952480475151;4761.40482439695;3714.647108524686;3768.2840260350995;3823.6941044716355;3857.479240302523;3887.71705337238;1202.5189924030458;1222.175031853513;1225.5526732742485;1236.4188831249298;1246.2896149194871;884.3500027479797;991.8264303746815;1022.5398570643091;1040.8282633365561;1041.7864337810731;5736.598438109376;5804.535637149018;5896.658659968367;5994.460498555064;6037.335893051761;441.7813182474696;444.11620874459595;459.37983721975064;463.15243105117554;465.4693649239869;4208.915821683549;4247.787610619456;4258.446401967724;4263.126059157865;4272.7222730523035;10063.446510441849;10251.538680759384;10326.122279606167;10330;10345.431001295;10167.491625418847;10234.385936843813;10237.692869213935;10260.743481413025;10272.640457584099;7907.169747176464;7937.523748575104;7939.801504962306;7988.640511177011;7989.0413150421155;5911.556633252438;5924.407559244213;6010.188624535632;6036.728347224298;6096.02463605821;9054.139856713602;9515.762105947406;9581.377210481296;9618.374805637612;9694.75763105928;9477.336586438883;9650.08324209199;9651.951740241253;9700.078492543196;9731.075547822946;6676.799696009128;7177.677004534913;7339.192688804121;7427.22014188524;7577.393808495417;8271.090180080066;8283.420160588701;8312.08567057611;8352;8376.958115209385;7478.439117066133;7716.498427602188;7732.149463559116;7743.186965368781;7785.260400261966;7516.5114267572435;7520.420711650396;7555.168931417649;7600.391968642606;7654.196309387434;6988.510804243654;6992.1259842519685;7009;7055.717771289091;7094.64526773649;5168.8449346519665;5279.366476022902;5331.973340133274;5373.301470808769;5528.115501519737;5213.728162557304;5266.104762190514;5269.683818970874;5336.039512887717;5358.035553600389 22:25:36 INFO - PROCESS | 6597 | |4;prototype.html;321.13934655124257;326.42060342891364;332.60420100080904;335.3460751534505;338.20015662957104;5405.648632838873;5527.834164975057;5588.329400471944;5660.877171060853;5687.63030403024;5568.86077848054;5576.637518561301;5588.524975377088;5613.07384281593;5637.830865074044;4404.735715857043;4415.889602759925;4421.960839202777;4427.645788336941;4431.293951828537;4176.45706058212;4398.582134697199;4403.185410699015;4420.458236514018;4451.5103338632725;4316.460442444694;4319.589638984291;4326.415933848922;4358;4409.162259170747;4322.351647252905;4379.912401751979;4381.78091095444;4388.341748737683;4407.427034485529;3768.1898391845884;3833.5208098987628;3837.3860182370686;3843.961560384388;3847.2882516734494;5475.6440831346235;5496.258005169331;5510.338759348863;5518.33779946409;5519;4963.7766300516205;4973.726445045521;4985.501449855022;5005.149124648782;5008.649394542384;5657.8585535361535;5885.676287804169;5886.793962211302;5887;5894.701066584035;4169.541350451448;4170.9582904170875;4178.791060446996;4195.139996300751;4223.97888010558;6211.34780848009;6212.223472065992;6212.502999760009;6220.377962203744;6256.655883926383;4218.177455396169;4264.275073237557;4286.571342865688;4294.248506511411;4314.69797114199;4301.978490107529;4307.22469955411;4312.54718254585;4355.346697995326;4361.672874534423;5017.347744793154;5026.472220416804;5038.193888977746;5040.57155141817;5046.318746969112;5639.689817060019;5949.881002379928;5952.3154837193415;6011.609245399035;6030.276366836008;1477.0842077911548;1492.2389581313607;1495.110409306461;1502.369005017895;1505.646173149309;934.2228830802894;943.1511639524346;950.4867371619248;959.2565761534867;960.4525420510242;5040.672356296829;5109.2117758784125;5113.258577506241;5145.845624631267;5161.425765141593;5382.838514844561;5386.326709161355;5390.97304513475;5396.487333703292;5444.121777986588;5030.542935986165;5050.6464547482055;5069.949300507022;5111.156659151198;5131.512506311894;2890.9763165784034;2911.7525010374356;2919.71532525688;2920.5765164051095;2954.985225073861;5548.447714051582;5580.832575022756;5604.915926261109;5673.517750991129;5688.573356998241;5346.742215234288;5371.409144994048;5467.4532546745;5476.780928762805;5498.762778374834;5532.474414930575;5578.7447824240035;5595.272614560163;5602.887942241132;5642.830715078554;5359.008583412081;5365.946340536545;5408.702521361363;5506.238003838733;5666.7533142708535;6067.998780201217;6070.876887775776;6075.635461872125;6089.756409743561;6110.297315808739;6042.305134909366;6185.505159587312;6367.707625254812;6376.298607153117;6383.116980490673;4218.460262004408;4230.111676547959;4240.724352917051;4251.171021650748;4257.233697934402;6140.600860944024;6201.720922558586;6219.751209951733;6227.878981783322;6230.844228894314;5818.2436283282905;5916.822495325147;5952.732127054206;5956;5991.580589358703;5966.940330596639;5980.949715645139;6019.729112189874;6026.457618814327;6035.36628653985;5797.710114494343;5953;5997.640141591519;6013.248343957005;6047.460066771879;5994.700264986646;6001.2498437695285;6028.547858910687;6037.607555508878;6051.243594550681;5750.223719797774;5775;5841;5850.151727999331;5851.683371241436 22:25:36 INFO - PROCESS | 6597 | |5;yui.html;759.3697231298023;812.9756107316779;839.5256679975813;849.2356878809068;865.1737590600977;14822.407103715852;16479.67040659186;16512;16525.917370413146;16548;15373.461928832494;15652.84347156528;15876.761848572281;16043.438479653214;16057.277422515986;826.0500424511799;836.4103307168432;837.4849467577426;840.3445312656107;840.3781201910573;809.6478032056045;831.7005877883956;835.1898658301438;836.0469065265602;836.2849763452252;861.8147098373848;867.4925168776274;867.8611422172454;869.4696235296495;874.3442418186361;862.5730263519566;868.6178081644057;869.2481003931586;870.8127752533204;874.6763697431927;629.2291942370605;630.4137152448221;633.0283015571122;633.1326083265955;635.139386131794;14522.419103235858;14549.12705237689;14586.416543338253;14623.561293161221;14669.633259168499;15227.619309517351;15450.690986180325;15452;15474.45839395627;15565.455209067628;12549.937250313782;12623.747525049448;12678.239305641599;12740;12821.102522823407;3964.444977703124;3965.345717956534;3973.304671682473;3975.9602403975878;4076.8165432555606;82360.58819705863;83150;83710;84644;85253.99999999937;10724.302920310154;10796.298240614335;10870.456477176267;11510.366929818772;11638.883611163948;21523.461913452287;21539.78460215409;21596.676049859263;21651.56696866054;21661.783382166293;8592.269657079094;8731.214190722738;8768.824623507495;8799.692010779592;9001.954990225007;4838.491958344393;4892.657513974059;4930.112579735682;4945.060438516671;5046.091703493406;3557.626449222846;3634.509341238952;3672.394054980898;3680.8895733128047;3878.204967981557;708.9432845372357;710.9573425594377;716.4698123388655;718.0521711341084;725.0755287009106;649.8635286589774;767.7543186180369;799.5562462833067;800.3237264511479;810.4124509730399;10436.37381757097;10634.415107169025;10855.131589472823;10860.294080884718;11114.110871130451;666.6433458099893;762.5043721580906;767.6699019421591;768.1588660416835;795.9562224077731;4883.169861123618;5043.394792624908;5053.36832895888;5110.2590124431745;5186.559142472856;121120.78879211271;129868.35065824799;131056.3447182777;133020.679172832;133328;112189;116772;117547.41226293985;119814;120421.39789300997;63993.36006639967;64301;101624;102239;103350;9715.854262186214;9725.562349694139;9748.610055597697;9869.703908882744;9964.55159517838;56973.7328893813;57057.71471142784;58269.70865145647;58332;58462.4153758474;81498.59250703946;83552.58223708843;85020.14979850122;85931;86024;13652.180869147884;13722.245276509888;13816.378262977989;14037.929810350883;14115.294235287989;16035.278412471234;16253.67492650164;16283.430079947146;16337;16661.416850410176;13852.16886986784;14151.150930944177;14294.285285735463;14706.852931470548;14737.778933316224;14685.779713304522;15056.548303550911;15102;15355;15904.681906361577;14157.221352825281;14707.92646036763;14763.92618036903;14892.553223403314;15710.450134245246;12248.938755306166;12467.376631168587;12695.619131426072;12766.382891507405;12809;11224;11688.770207708696;12040.698982525508;12646.810297845355;12744.872551274368 22:25:36 INFO - PROCESS | 6597 | __end_tp_report 22:25:36 INFO - PROCESS | 6597 | __start_cc_report 22:25:36 INFO - PROCESS | 6597 | _x_x_mozilla_cycle_collect,365 22:25:36 INFO - PROCESS | 6597 | __end_cc_report 22:25:36 INFO - PROCESS | 6597 | __startTimestamp1468992336328__endTimestamp 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | ------- Summary: start ------- 22:25:36 INFO - PROCESS | 6597 | Number of tests: 6 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | [#0] dojo.html Cycles:165 Average:5494.20 Median:5694.15 stddev:643.60 (11.3%) stddev-sans-first:617.59 22:25:36 INFO - PROCESS | 6597 | Values: 3101.3 3101.8 3115.3 3118.0 3135.1 5475.8 5513.1 5522.6 5522.8 5525.4 5444.8 5516.3 5535.4 5586.2 5616.4 4931.9 4956.9 4985.4 4991.9 5021.4 4952.6 5022.9 5041.3 5062.0 5094.0 4890.2 4951.8 4963.1 4996.3 5043.2 4978.7 5017.5 5025.6 5053.6 5075.7 4999.8 5001.6 5023.4 5037.6 5071.2 5370.2 5428.9 5453.9 5530.5 5546.1 5844.6 5897.8 5932.6 5955.3 5965.7 5784.7 5973.8 5983.3 6000.3 6001.3 5505.3 5515.4 5528.6 5556.4 5558.1 6061.4 6063.4 6069.6 6072.8 6083.3 5632.9 5745.5 5777.3 5828.4 5846.5 5874.6 5917.6 5917.7 5923.9 5959.4 5831.7 5878.6 5888.1 5894.1 5920.7 5674.9 5843.6 5850.5 5928.2 5931.3 4425.4 4502.5 4512.8 4530.1 4551.3 3994.0 4030.5 4046.0 4068.0 4116.4 5483.6 5612.8 5617.4 5694.1 5719.4 5947.0 5982.3 5987.8 6032.5 6044.1 5554.4 5607.8 5636.4 5640.4 5656.5 4925.4 4984.7 5114.2 5114.3 5140.4 6178.9 6181.1 6190.7 6198.8 6199.5 5980.9 5998.7 5999.5 6003.4 6029.0 6017.1 6086.9 6116.4 6139.9 6143.7 5868.6 5880.1 5883.3 5906.5 5933.5 5867.2 5914.2 5991.8 5992.9 5997.9 5615.9 5792.8 5821.1 5856.6 5857.9 5797.2 5827.4 5827.9 5831.8 5856.4 5553.3 5855.1 5862.9 5864.8 5867.8 5720.4 5727.2 5737.9 5755.9 5774.8 5487.9 5598.6 5618.4 5635.2 5656.5 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | [#1] ext.html Cycles:180 Average:63171.28 Median:42389.29 stddev:132970.54 (313.7%) stddev-sans-first:133260.48 22:25:36 INFO - PROCESS | 6597 | Values: 566.1 592.3 603.8 616.1 646.2 41696.8 46103.3 46125.3 46219.5 46319.0 46150.0 46341.0 46799.5 46812.0 46830.3 8888.9 9027.3 9048.7 9073.9 9084.8 9066.5 9144.1 9153.6 9160.0 9162.0 9130.0 9150.1 9156.9 9184.1 9195.9 9069.8 9078.9 9134.7 9186.8 9197.6 8857.7 8904.6 8950.2 8997.4 9026.6 33764.2 43228.8 43441.0 43578.6 43697.1 3464.7 3507.6 3658.5 3793.2 3817.0 33050.8 33463.2 33626.7 34524.1 34969.5 4297.3 4316.6 4333.1 4335.7 4347.7 328269.0 329579.0 330882.0 331150.0 331464.0 35474.3 35776.5 36388.6 36573.3 36644.3 37913.4 37972.0 38013.0 38024.2 38054.2 15844.4 15851.0 15942.6 15953.4 15956.8 13364.7 13443.7 13471.7 13476.7 13485.1 8443.4 8444.3 8456.0 8458.2 8575.1 3232.3 3277.9 3282.8 3321.6 3324.4 1062.6 1078.1 1094.6 1105.6 1187.4 34083.7 34163.1 34176.8 34215.7 34749.5 1158.5 1196.2 1245.2 1277.0 1309.9 17228.5 17831.1 18000.2 18036.0 18112.2 613108.0 615274.0 619102.0 619343.0 621102.0 62297.4 63400.7 63880.7 63974.0 64001.7 1354.1 1384.5 1398.0 1401.4 1404.4 32430.7 34065.6 34237.3 34486.8 34826.7 55607.0 55704.7 55707.0 55892.7 56209.4 499198.0 502929.0 505559.0 506434.0 506976.0 26471.3 27637.8 27652.9 29129.0 29414.8 48571.3 48743.8 49273.8 49910.5 50094.7 41396.4 42285.0 42493.6 42950.6 43051.8 41985.6 42525.6 42696.1 42975.0 43062.0 40463.0 43899.6 44920.3 44957.8 46112.5 36191.6 39970.2 40277.4 40559.0 40567.8 29242.3 29769.4 30307.5 30860.0 30957.0 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | [#2] jquery.html Cycles:180 Average:31125.49 Median:24250.76 stddev:88240.23 (363.9%) stddev-sans-first:88457.58 22:25:36 INFO - PROCESS | 6597 | Values: 382.9 386.4 393.1 395.8 414.4 14513.9 19089.9 19201.4 20944.4 20952.4 19765.1 19928.0 20040.7 21121.0 21208.4 5736.1 5816.4 5847.5 5854.4 5873.1 5697.4 5770.7 5771.8 5815.0 5838.4 5612.4 5697.6 5717.1 5752.4 5874.2 5671.5 5723.1 5723.6 5726.3 5758.0 5073.9 5133.3 5164.8 5189.0 5202.7 23989.8 24152.5 24349.0 24360.8 24889.1 2439.4 2469.8 2545.6 2679.5 3049.5 14664.9 15435.8 15457.7 15524.6 15720.5 1897.0 1976.6 2027.0 2046.5 2059.9 79390.0 81985.6 83032.6 83689.6 84909.0 10929.5 11271.7 11303.7 11306.8 11314.5 15456.8 15527.8 15554.2 15566.7 15649.8 8277.2 8466.5 8583.8 8714.7 8736.0 8328.2 8374.8 8426.1 8554.4 8571.8 4689.1 4704.0 4782.9 4843.2 4890.6 1982.1 1988.4 2000.0 2007.3 2038.9 917.3 966.8 1018.8 1079.6 1081.9 18427.4 20163.4 20613.2 20628.5 20792.8 998.3 1000.6 1033.8 1067.2 1070.5 10340.4 11452.3 11497.5 11549.7 11560.4 526030.0 534291.0 537078.0 537523.0 540440.0 90346.5 99968.5 105916.9 106006.5 106261.0 1008.4 1014.6 1034.8 1042.0 1064.0 18065.3 18095.3 18583.7 18730.6 18909.0 40590.8 43960.5 44257.6 44608.8 45062.5 62093.4 63260.7 63898.4 64885.4 65591.3 7300.4 7754.3 8623.7 8644.7 8647.2 13363.7 13662.6 13957.7 14029.6 14145.9 12405.0 12413.6 12542.4 12652.9 12698.7 11688.5 11946.8 12015.5 12019.3 12055.3 11931.8 12072.5 12098.6 12434.3 12533.7 7899.4 7946.6 7985.6 8001.8 8172.1 8993.7 10666.3 11644.5 11678.0 11719.5 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | [#3] mootools.html Cycles:180 Average:5620.45 Median:5138.72 stddev:3136.09 (61.0%) stddev-sans-first:3129.40 22:25:36 INFO - PROCESS | 6597 | Values: 1473.6 1516.1 1652.5 1771.9 1784.6 7476.3 8127.7 8172.8 8703.1 8722.5 8228.5 8406.2 8442.9 8445.0 8481.4 2234.9 2291.1 2293.4 2298.3 2299.3 2282.4 2316.1 2322.5 2330.9 2331.4 2265.9 2269.7 2286.5 2323.9 2326.5 2211.8 2217.9 2223.1 2225.6 2245.0 2207.1 2213.9 2219.0 2221.2 2228.1 8492.6 8544.1 8547.0 8601.1 8607.9 4805.4 5081.9 5166.9 5176.0 5186.1 3211.5 3254.2 3400.1 3431.1 3434.1 1119.4 1129.6 1138.7 1148.9 1152.9 11633.8 11701.2 11737.5 11737.9 11740.2 4332.7 4434.2 4546.1 4561.2 4566.8 9893.6 9955.1 9960.6 9963.0 9970.7 5126.9 5150.5 5150.6 5170.1 5175.1 4273.4 4560.6 4678.3 4752.0 4761.4 3714.6 3768.3 3823.7 3857.5 3887.7 1202.5 1222.2 1225.6 1236.4 1246.3 884.4 991.8 1022.5 1040.8 1041.8 5736.6 5804.5 5896.7 5994.5 6037.3 441.8 444.1 459.4 463.2 465.5 4208.9 4247.8 4258.4 4263.1 4272.7 10063.4 10251.5 10326.1 10330.0 10345.4 10167.5 10234.4 10237.7 10260.7 10272.6 7907.2 7937.5 7939.8 7988.6 7989.0 5911.6 5924.4 6010.2 6036.7 6096.0 9054.1 9515.8 9581.4 9618.4 9694.8 9477.3 9650.1 9652.0 9700.1 9731.1 6676.8 7177.7 7339.2 7427.2 7577.4 8271.1 8283.4 8312.1 8352.0 8377.0 7478.4 7716.5 7732.1 7743.2 7785.3 7516.5 7520.4 7555.2 7600.4 7654.2 6988.5 6992.1 7009.0 7055.7 7094.6 5168.8 5279.4 5332.0 5373.3 5528.1 5213.7 5266.1 5269.7 5336.0 5358.0 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | [#4] prototype.html Cycles:180 Average:4853.51 Median:5455.79 stddev:1431.39 (26.2%) stddev-sans-first:1394.40 22:25:36 INFO - PROCESS | 6597 | Values: 321.1 326.4 332.6 335.3 338.2 5405.6 5527.8 5588.3 5660.9 5687.6 5568.9 5576.6 5588.5 5613.1 5637.8 4404.7 4415.9 4422.0 4427.6 4431.3 4176.5 4398.6 4403.2 4420.5 4451.5 4316.5 4319.6 4326.4 4358.0 4409.2 4322.4 4379.9 4381.8 4388.3 4407.4 3768.2 3833.5 3837.4 3844.0 3847.3 5475.6 5496.3 5510.3 5518.3 5519.0 4963.8 4973.7 4985.5 5005.1 5008.6 5657.9 5885.7 5886.8 5887.0 5894.7 4169.5 4171.0 4178.8 4195.1 4224.0 6211.3 6212.2 6212.5 6220.4 6256.7 4218.2 4264.3 4286.6 4294.2 4314.7 4302.0 4307.2 4312.5 4355.3 4361.7 5017.3 5026.5 5038.2 5040.6 5046.3 5639.7 5949.9 5952.3 6011.6 6030.3 1477.1 1492.2 1495.1 1502.4 1505.6 934.2 943.2 950.5 959.3 960.5 5040.7 5109.2 5113.3 5145.8 5161.4 5382.8 5386.3 5391.0 5396.5 5444.1 5030.5 5050.6 5069.9 5111.2 5131.5 2891.0 2911.8 2919.7 2920.6 2955.0 5548.4 5580.8 5604.9 5673.5 5688.6 5346.7 5371.4 5467.5 5476.8 5498.8 5532.5 5578.7 5595.3 5602.9 5642.8 5359.0 5365.9 5408.7 5506.2 5666.8 6068.0 6070.9 6075.6 6089.8 6110.3 6042.3 6185.5 6367.7 6376.3 6383.1 4218.5 4230.1 4240.7 4251.2 4257.2 6140.6 6201.7 6219.8 6227.9 6230.8 5818.2 5916.8 5952.7 5956.0 5991.6 5966.9 5980.9 6019.7 6026.5 6035.4 5797.7 5953.0 5997.6 6013.2 6047.5 5994.7 6001.2 6028.5 6037.6 6051.2 5750.2 5775.0 5841.0 5850.2 5851.7 22:25:36 INFO - PROCESS | 6597 | 22:25:36 INFO - PROCESS | 6597 | [#5] yui.html Cycles:180 Average:22804.17 Median:3676.64 stddev:33775.93 (918.7%) stddev-sans-first:33830.12 22:25:36 INFO - PROCESS | 6597 | Values: 759.4 813.0 839.5 849.2 865.2 14822.4 16479.7 16512.0 16525.9 16548.0 15373.5 15652.8 15876.8 16043.4 16057.3 826.1 836.4 837.5 840.3 840.4 809.6 831.7 835.2 836.0 836.3 861.8 867.5 867.9 869.5 874.3 862.6 868.6 869.2 870.8 874.7 629.2 630.4 633.0 633.1 635.1 14522.4 14549.1 14586.4 14623.6 14669.6 15227.6 15450.7 15452.0 15474.5 15565.5 12549.9 12623.7 12678.2 12740.0 12821.1 3964.4 3965.3 3973.3 3976.0 4076.8 82360.6 83150.0 83710.0 84644.0 85254.0 10724.3 10796.3 10870.5 11510.4 11638.9 21523.5 21539.8 21596.7 21651.6 21661.8 8592.3 8731.2 8768.8 8799.7 9002.0 4838.5 4892.7 4930.1 4945.1 5046.1 3557.6 3634.5 3672.4 3680.9 3878.2 708.9 711.0 716.5 718.1 725.1 649.9 767.8 799.6 800.3 810.4 10436.4 10634.4 10855.1 10860.3 11114.1 666.6 762.5 767.7 768.2 796.0 4883.2 5043.4 5053.4 5110.3 5186.6 121120.8 129868.4 131056.3 133020.7 133328.0 112189.0 116772.0 117547.4 119814.0 120421.4 63993.4 64301.0 101624.0 102239.0 103350.0 9715.9 9725.6 9748.6 9869.7 9964.6 56973.7 57057.7 58269.7 58332.0 58462.4 81498.6 83552.6 85020.1 85931.0 86024.0 13652.2 13722.2 13816.4 14037.9 14115.3 16035.3 16253.7 16283.4 16337.0 16661.4 13852.2 14151.2 14294.3 14706.9 14737.8 14685.8 15056.5 15102.0 15355.0 15904.7 14157.2 14707.9 14763.9 14892.6 15710.5 12248.9 12467.4 12695.6 12766.4 12809.0 11224.0 11688.8 12040.7 12646.8 12744.9 22:25:36 INFO - PROCESS | 6597 | -------- Summary: end -------- 22:25:36 INFO - PROCESS | 6597 | 22:25:37 INFO - TEST-INFO | 6597: exit 0 22:25:37 INFO - TEST-OK | dromaeo_css | took 1111040ms 22:25:37 INFO - TEST-START | kraken 22:25:37 INFO - operating with platform_type : linux_ 22:25:37 INFO - Initialising browser for kraken test... 22:25:37 INFO - TEST-INFO | started process 6850 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppVoP_P/profile http://localhost:53887/getInfo.html) 22:25:41 INFO - PROCESS | 6850 | __metrics Screen width/height:1600/1200 22:25:41 INFO - PROCESS | 6850 | colorDepth:24 22:25:41 INFO - PROCESS | 6850 | Browser inner width/height: 1024/697 22:25:41 INFO - PROCESS | 6850 | __metrics 22:25:41 ERROR - PROCESS | 6850 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 22:25:42 INFO - TEST-INFO | 6850: exit 0 22:25:42 INFO - Browser initialized. 22:25:42 INFO - Running cycle 1/1 for kraken test... 22:25:42 INFO - TEST-INFO | started process 6969 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppVoP_P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:25:54 INFO - PROCESS | 6969 | RSS: Main: 167702528 22:25:54 INFO - PROCESS | 6969 | 22:25:57 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53887/tests/kraken/driver.html?testName=audio-beat-detection) 22:25:57 INFO - PROCESS | 6969 | RSS: Main: 173174784 22:25:57 INFO - PROCESS | 6969 | 22:26:00 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53887/tests/kraken/driver.html?testName=audio-dft) 22:26:01 INFO - PROCESS | 6969 | RSS: Main: 172457984 22:26:01 INFO - PROCESS | 6969 | 22:26:04 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53887/tests/kraken/driver.html?testName=audio-fft) 22:26:04 INFO - PROCESS | 6969 | RSS: Main: 167165952 22:26:04 INFO - PROCESS | 6969 | 22:26:06 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53887/tests/kraken/driver.html?testName=audio-oscillator) 22:26:07 INFO - PROCESS | 6969 | RSS: Main: 168337408 22:26:07 INFO - PROCESS | 6969 | 22:26:09 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53887/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:26:10 INFO - PROCESS | 6969 | RSS: Main: 168583168 22:26:10 INFO - PROCESS | 6969 | 22:26:13 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53887/tests/kraken/driver.html?testName=imaging-darkroom) 22:26:14 INFO - PROCESS | 6969 | RSS: Main: 168931328 22:26:14 INFO - PROCESS | 6969 | 22:26:17 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53887/tests/kraken/driver.html?testName=imaging-desaturate) 22:26:18 INFO - PROCESS | 6969 | RSS: Main: 166174720 22:26:18 INFO - PROCESS | 6969 | 22:26:21 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53887/tests/kraken/driver.html?testName=json-parse-financial) 22:26:21 INFO - PROCESS | 6969 | RSS: Main: 167649280 22:26:21 INFO - PROCESS | 6969 | 22:26:23 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53887/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:26:23 INFO - PROCESS | 6969 | RSS: Main: 168488960 22:26:23 INFO - PROCESS | 6969 | 22:26:25 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:26:26 INFO - PROCESS | 6969 | RSS: Main: 168542208 22:26:26 INFO - PROCESS | 6969 | 22:26:28 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:26:29 INFO - PROCESS | 6969 | RSS: Main: 168988672 22:26:29 INFO - PROCESS | 6969 | 22:26:31 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:26:32 INFO - PROCESS | 6969 | RSS: Main: 169877504 22:26:32 INFO - PROCESS | 6969 | 22:26:34 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:26:35 INFO - PROCESS | 6969 | RSS: Main: 169349120 22:26:35 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | Cycle 1(1): loaded http://localhost:53887/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53887/tests/kraken/driver.html?testName=ai-astar) 22:26:37 INFO - PROCESS | 6969 | RSS: Main: 169521152 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | __start_tp_report 22:26:37 INFO - PROCESS | 6969 | _x_x_mozilla_page_load 22:26:37 INFO - PROCESS | 6969 | _x_x_mozilla_page_load_details 22:26:37 INFO - PROCESS | 6969 | |i|pagename|runs| 22:26:37 INFO - PROCESS | 6969 | |0;ai-astar;103;112;106;112;111;114;114;112;111;112 22:26:37 INFO - PROCESS | 6969 | |1;audio-beat-detection;147;117;145;178;120;144;144;123;193;118 22:26:37 INFO - PROCESS | 6969 | |2;audio-dft;162;162;163;161;161;164;175;164;161;160 22:26:37 INFO - PROCESS | 6969 | |3;audio-fft;74;74;76;163;76;76;76;75;180;75 22:26:37 INFO - PROCESS | 6969 | |4;audio-oscillator;87;86;151;85;84;84;150;85;85;143 22:26:37 INFO - PROCESS | 6969 | |5;imaging-gaussian-blur;117;117;121;118;119;117;117;117;118;117 22:26:37 INFO - PROCESS | 6969 | |6;imaging-darkroom;147;148;147;147;147;146;147;147;147;148 22:26:37 INFO - PROCESS | 6969 | |7;imaging-desaturate;97;96;95;95;97;96;95;96;95;95 22:26:37 INFO - PROCESS | 6969 | |8;json-parse-financial;53;53;54;55;60;53;60;53;60;53 22:26:37 INFO - PROCESS | 6969 | |9;json-stringify-tinderbox;61;62;61;61;62;61;62;61;63;61 22:26:37 INFO - PROCESS | 6969 | |10;stanford-crypto-aes;77;75;77;75;78;75;75;76;84;77 22:26:37 INFO - PROCESS | 6969 | |11;stanford-crypto-ccm;124;124;119;121;133;123;123;129;178;122 22:26:37 INFO - PROCESS | 6969 | |12;stanford-crypto-pbkdf2;157;156;158;162;157;154;156;168;157;155 22:26:37 INFO - PROCESS | 6969 | |13;stanford-crypto-sha256-iterative;64;67;70;64;62;65;62;63;63;63 22:26:37 INFO - PROCESS | 6969 | __end_tp_report 22:26:37 INFO - PROCESS | 6969 | __start_cc_report 22:26:37 INFO - PROCESS | 6969 | _x_x_mozilla_cycle_collect,918 22:26:37 INFO - PROCESS | 6969 | __end_cc_report 22:26:37 INFO - PROCESS | 6969 | __startTimestamp1468992397536__endTimestamp 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | ------- Summary: start ------- 22:26:37 INFO - PROCESS | 6969 | Number of tests: 14 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#0] ai-astar Cycles:10 Average:110.70 Median:112.00 stddev:3.50 (3.1%) stddev-sans-first:2.35 22:26:37 INFO - PROCESS | 6969 | Values: 103.0 112.0 106.0 112.0 111.0 114.0 114.0 112.0 111.0 112.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:144.50 stddev:25.77 (17.8%) stddev-sans-first:27.29 22:26:37 INFO - PROCESS | 6969 | Values: 147.0 117.0 145.0 178.0 120.0 144.0 144.0 123.0 193.0 118.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#2] audio-dft Cycles:10 Average:163.30 Median:162.50 stddev:4.32 (2.7%) stddev-sans-first:4.56 22:26:37 INFO - PROCESS | 6969 | Values: 162.0 162.0 163.0 161.0 161.0 164.0 175.0 164.0 161.0 160.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.50 stddev:40.79 (54.0%) stddev-sans-first:42.58 22:26:37 INFO - PROCESS | 6969 | Values: 74.0 74.0 76.0 163.0 76.0 76.0 76.0 75.0 180.0 75.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:85.00 stddev:30.44 (35.8%) stddev-sans-first:31.66 22:26:37 INFO - PROCESS | 6969 | Values: 87.0 86.0 151.0 85.0 84.0 84.0 150.0 85.0 85.0 143.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:117.50 stddev:1.32 (1.1%) stddev-sans-first:1.36 22:26:37 INFO - PROCESS | 6969 | Values: 117.0 117.0 121.0 118.0 119.0 117.0 117.0 117.0 118.0 117.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 22:26:37 INFO - PROCESS | 6969 | Values: 147.0 148.0 147.0 147.0 147.0 146.0 147.0 147.0 147.0 148.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 22:26:37 INFO - PROCESS | 6969 | Values: 97.0 96.0 95.0 95.0 97.0 96.0 95.0 96.0 95.0 95.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:54.50 stddev:3.24 (5.9%) stddev-sans-first:3.32 22:26:37 INFO - PROCESS | 6969 | Values: 53.0 53.0 54.0 55.0 60.0 53.0 60.0 53.0 60.0 53.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:61.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 22:26:37 INFO - PROCESS | 6969 | Values: 61.0 62.0 61.0 61.0 62.0 61.0 62.0 61.0 63.0 61.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:77.00 stddev:2.73 (3.5%) stddev-sans-first:2.89 22:26:37 INFO - PROCESS | 6969 | Values: 77.0 75.0 77.0 75.0 78.0 75.0 75.0 76.0 84.0 77.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:124.00 stddev:17.48 (14.1%) stddev-sans-first:18.42 22:26:37 INFO - PROCESS | 6969 | Values: 124.0 124.0 119.0 121.0 133.0 123.0 123.0 129.0 178.0 122.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.00 Median:157.00 stddev:4.11 (2.6%) stddev-sans-first:4.34 22:26:37 INFO - PROCESS | 6969 | Values: 157.0 156.0 158.0 162.0 157.0 154.0 156.0 168.0 157.0 155.0 22:26:37 INFO - PROCESS | 6969 | 22:26:37 INFO - PROCESS | 6969 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.00 stddev:2.50 (3.9%) stddev-sans-first:2.65 22:26:37 INFO - PROCESS | 6969 | Values: 64.0 67.0 70.0 64.0 62.0 65.0 62.0 63.0 63.0 63.0 22:26:37 INFO - PROCESS | 6969 | -------- Summary: end -------- 22:26:37 INFO - PROCESS | 6969 | 22:26:38 INFO - TEST-INFO | 6969: exit 0 22:26:38 INFO - TEST-OK | kraken | took 61446ms 22:26:38 INFO - SUITE-END | took 1172s 22:26:38 INFO - Completed test suite (00:19:32) 22:26:38 INFO - javascript benchmark 22:26:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7372.029502491292, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3101.3177101037772, 3101.8449077546115, 3115.33020400614, 3117.9532307015397, 3135.1378370947987, 5475.753591088395, 5513.145462453316, 5522.585806064541, 5522.806701765439, 5525.364583072937, 5444.7822087116565, 5516.255305533752, 5535.418781027995, 5586.162075688649, 5616.354119276284, 4931.876703082425, 4956.87607809804, 4985.40175178979, 4991.925121123186, 5021.447640759514, 4952.603791696655, 5022.8949631081105, 5041.294218809369, 5062.038212739586, 5094.0, 4890.21756518957, 4951.826686065989, 4963.131451995886, 4996.325496058022, 5043.167877300241, 4978.701277923336, 5017.473165317626, 5025.623078269108, 5053.646244762868, 5075.720835354054, 4999.750012499397, 5001.649884508086, 5023.397192336906, 5037.622178336639, 5071.188609822448, 5370.248165256867, 5428.864278393033, 5453.863653408657, 5530.474604912527, 5546.140348245988, 5844.620099693507, 5897.764089436418, 5932.584719069667, 5955.315138759055, 5965.671888046156, 5784.652920824764, 5973.820785376446, 5983.311919129313, 6000.309964354112, 6001.309849367335, 5505.339359276913, 5515.420880807537, 5528.585356098229, 5556.361018482886, 5558.055130627803, 6061.424164704346, 6063.423974722395, 6069.635821850703, 6072.787452439144, 6083.300420451616, 5632.943670563324, 5745.482906538432, 5777.306723193244, 5828.358880523139, 5846.503047240947, 5874.5887787854435, 5917.644941303535, 5917.733701983421, 5923.940760592425, 5959.374265702038, 5831.737571809279, 5878.559108066912, 5888.146218798336, 5894.115882617642, 5920.674362909996, 5674.858128546819, 5843.561732869966, 5850.502707269845, 5928.170056192136, 5931.288245410578, 4425.35832304314, 4502.482214545304, 4512.819487220474, 4530.07133537624, 4551.294549344857, 3994.021464741099, 4030.5163380394542, 4045.959540404617, 4068.044009657728, 4116.403121547419, 5483.616146869761, 5612.831615051555, 5617.38208797032, 5694.1458781183155, 5719.399463056402, 5946.970265148647, 5982.252218472691, 5987.790427335022, 6032.457078862835, 6044.063170208625, 5554.361248456398, 5607.80372686954, 5636.351819540805, 5640.379558248589, 5656.490915817596, 4925.359703238556, 4984.675996060245, 5114.181730923109, 5114.28400023997, 5140.383154021467, 6178.907316390258, 6181.072839074175, 6190.721417536221, 6198.845028874314, 6199.504039676815, 5980.940190598125, 5998.700064996646, 5999.4900433463645, 6003.369646187088, 6029.0, 6017.06735456005, 6086.908696369469, 6116.366205733216, 6139.9079013813935, 6143.723532440961, 5868.559858010581, 5880.088586269135, 5883.264591926009, 5906.497947674324, 5933.525317974638, 5867.20792692998, 5914.172015917689, 5991.7902873399225, 5992.8801423972145, 5997.880042399041, 5615.943840561706, 5792.768289268551, 5821.09772985188, 5856.560757943256, 5857.853553661193, 5797.246357973437, 5827.358990511125, 5827.854303642443, 5831.79588714393, 5856.355800861987, 5553.250311207935, 5855.121731740273, 5862.94137058624, 5864.794732184524, 5867.765289388377, 5720.3707592164, 5727.198192253171, 5737.9139312911175, 5755.942440575541, 5774.769009239583, 5487.917681234705, 5598.636088654224, 5618.438156184349, 5635.182898479616, 5656.547476201977], "value": 5449.057215167993, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.0772940107627, 592.3277080513617, 603.7977277612, 616.1004932798116, 646.1535388640877, 41696.791516042416, 46103.30845037319, 46125.30812037818, 46219.53780462194, 46319.0, 46150.0, 46341.0, 46799.532004679946, 46812.0, 46830.297545536756, 8888.866667, 9027.277817774562, 9048.728538143834, 9073.863892041625, 9084.772880678003, 9066.501342426196, 9144.131307525808, 9153.588088536031, 9159.954200229024, 9162.037986011474, 9130.04134565871, 9150.130737579919, 9156.908430915706, 9184.08159184083, 9195.862062069074, 9069.773255668628, 9078.86381704275, 9134.680286189985, 9186.816263674722, 9197.632094716204, 8857.734267971971, 8904.643814247422, 8950.194482496541, 8997.415168014124, 9026.638934442615, 33764.15589610279, 43228.78385608083, 43441.045152967876, 43578.564214358084, 43697.12605747899, 3464.6535346465403, 3507.6141624421293, 3658.5243918290716, 3793.2223894101644, 3817.0075780297043, 33050.83474582612, 33463.186404832304, 33626.663733362846, 34524.13689657779, 34969.47545786815, 4297.269464191095, 4316.589804312602, 4333.06839029607, 4335.6965012449145, 4347.69566130371, 328269.0, 329579.0, 330882.0, 331150.0, 331464.0, 35474.290514190085, 35776.46335304921, 36388.636113639055, 36573.26853462916, 36644.26711465756, 37913.431298530544, 37972.0, 38013.0, 38024.23951520954, 38054.23891522154, 15844.366225350857, 15851.048937063813, 15942.601434963986, 15953.361865525481, 15956.76064859028, 13364.6658833528, 13443.731125377437, 13471.663208419672, 13476.663083422993, 13485.123466974812, 8443.408961372768, 8444.282236010007, 8455.95772021136, 8458.238758511641, 8575.142485751376, 3232.2565809863863, 3277.9344413111603, 3282.8030318180986, 3321.6014078310272, 3324.401607710587, 1062.617457715222, 1078.1482628723377, 1094.584058057933, 1105.6019832860163, 1187.3825610682397, 34083.65916340854, 34163.14592135217, 34176.82911585476, 34215.65784342125, 34749.47875781865, 1158.5076342554548, 1196.174639498743, 1245.2217364147407, 1277.0358379423476, 1309.9148555343872, 17228.483145505656, 17831.127731588254, 18000.189991450417, 18036.028119507868, 18112.184951677205, 613108.0, 615274.0, 619102.0, 619343.0, 621102.0, 62297.37702623006, 63400.682996585645, 63880.68059659672, 63974.0, 64001.67999159975, 1354.1130559483554, 1384.5154196031308, 1398.028370282644, 1401.3623801170443, 1404.4382247101241, 32430.675693242767, 34065.59946490172, 34237.315253695284, 34486.82756586201, 34826.651733482344, 55607.0, 55704.72147639236, 55707.0, 55892.72053639706, 56209.43790562042, 499198.0, 502929.0, 505559.0, 506434.0, 506976.0, 26471.33821654474, 27637.756300966103, 27652.861735691193, 29129.0, 29414.82340706348, 48571.27143092786, 48743.75628121837, 49273.753631231615, 49910.50089499204, 50094.74952625213, 41396.379054313606, 42285.0, 42493.57506424896, 42950.570494295906, 43051.784741076095, 41985.58014419939, 42525.57474425216, 42696.14607707766, 42975.0, 43062.0, 40463.0, 43899.561004390824, 44920.32619510645, 44957.775211125045, 46112.53887461217, 36191.638083618825, 39970.200595987335, 40277.39583906185, 40559.0, 40567.79716101401, 29242.268943276587, 29769.364817025038, 30307.545386819656, 30860.0, 30957.0], "value": 19495.698483414675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.8927900187948, 386.3605732512689, 393.12529621591955, 395.83770654031844, 414.37636357282287, 14513.927430362834, 19089.904550477262, 19201.423957281288, 20944.37166884996, 20952.37142885714, 19765.110570024346, 19928.0, 20040.699389509133, 21121.0, 21208.36374908751, 5736.139579063132, 5816.360200377955, 5847.532197424217, 5854.443827836349, 5873.0603103503445, 5697.430256974311, 5770.682612456313, 5771.797987070453, 5814.970925145369, 5838.445347691983, 5612.382637909826, 5697.572682048843, 5717.142428635696, 5752.395998420168, 5874.236349274588, 5671.489565939044, 5723.112917497794, 5723.570732195081, 5726.31284245891, 5757.971210143923, 5073.934473760515, 5133.332666753336, 5164.793408263665, 5189.040027594907, 5202.739863006835, 23989.7601023991, 24152.51694966091, 24349.0, 24360.756392436026, 24889.128880489276, 2439.3657649011207, 2469.814763892697, 2545.6054311581734, 2679.517686816372, 3049.5273232648974, 14664.853351466561, 15435.845641543554, 15457.690846183126, 15524.61188470286, 15720.528384148494, 1896.9620607587908, 1976.5849171673965, 2026.9898650506652, 2046.5395286060666, 2059.938201853947, 79390.0, 81985.59007204926, 83032.58483707604, 83689.58155209245, 84909.0, 10929.540853307943, 11271.718207044889, 11303.660890173143, 11306.773864522664, 11314.490847911864, 15456.768148477782, 15527.767083493756, 15554.22228888551, 15566.688666226612, 15649.843501565067, 8277.213664701843, 8466.492010479267, 8583.828323433496, 8714.738557843275, 8736.0, 8328.208820162075, 8374.790630234293, 8426.07313195548, 8554.401191916506, 8571.828563428697, 4689.109069276827, 4704.0, 4782.856514304576, 4843.200871856175, 4890.559849613552, 1982.0882394098587, 1988.4233572263881, 1999.9500012499805, 2007.2773801431474, 2038.9388318350473, 917.3074328881652, 966.7776411425411, 1018.7758693087509, 1079.643717573199, 1081.8701755789355, 18427.355042573447, 20163.395098146786, 20613.175472980914, 20628.48428789262, 20792.792072079086, 998.2812375089924, 1000.6047611193537, 1033.7725700345911, 1067.215596536545, 1070.4594179939081, 10340.37957722539, 11452.312861228187, 11497.482613282422, 11549.653510394533, 11560.364179970182, 526030.0, 534291.0, 537078.0, 537523.0, 540440.0, 90346.54826725824, 99968.5001575002, 105916.94083059071, 106006.46996764967, 106261.0, 1008.4100801031332, 1014.6144465103214, 1034.8292531732332, 1042.0309112525272, 1063.9574417023232, 18065.2773889043, 18095.276188952295, 18583.721244181077, 18730.625387492448, 18909.0, 40590.79704601458, 43960.4668843077, 44257.55742442663, 44608.77695611501, 45062.549374505834, 62093.37906620876, 63260.683696581225, 63898.361016389244, 64885.351146487934, 65591.34408655853, 7300.415966722543, 7754.263344982218, 8623.698170564001, 8644.654213831376, 8647.178518040777, 13363.665908352368, 13662.590122296348, 13957.720845583235, 14029.579112626638, 14145.858541414455, 12405.0, 12413.627591171919, 12542.435590398272, 12652.936735316265, 12698.746025079261, 11688.47401866935, 11946.820797687868, 12015.519379224734, 12019.278843269432, 12055.276683399024, 11931.821022684493, 12072.517099316281, 12098.637040888787, 12434.316112613531, 12533.686657833627, 7899.447038707235, 7946.562939038177, 7985.600719964094, 8001.8399632008195, 8172.060213075572, 8993.730188094367, 10666.306690065121, 11644.53421863116, 11678.0, 11719.531218751154], "value": 9582.47021643922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.5505670770415, 1516.067618414675, 1652.4794689672751, 1771.8848274862141, 1784.6252287019718, 7476.28975247352, 8127.674893004273, 8172.7548173554815, 8703.129687031302, 8722.476651400912, 8228.465149765261, 8406.159384061591, 8442.873356899636, 8444.957775211114, 8481.448705834116, 2234.9217777377794, 2291.0950174680993, 2293.3693234360603, 2298.321995011467, 2299.31020693792, 2282.3609389370918, 2316.143027079983, 2322.477442575424, 2330.9184178553755, 2331.4404542909665, 2265.909363625453, 2269.716285464317, 2286.5084006938505, 2323.8838058097112, 2326.488172602025, 2211.8119959803435, 2217.866927984326, 2223.1440895255364, 2225.5771403433296, 2245.045855511401, 2207.128184367182, 2213.900374483152, 2219.04581030157, 2221.1781640792847, 2228.097620463715, 8492.617832197566, 8544.102869198769, 8547.0, 8601.053884072748, 8607.913920860774, 4805.447373552051, 5081.949180508222, 5166.9224961625605, 5176.0, 5186.118359878829, 3211.4701074322943, 3254.1701866024014, 3400.0819778659625, 3431.0907609483506, 3434.0899661589697, 1119.44027986007, 1129.6385156749843, 1138.6868611131922, 1148.8736239013701, 1152.8904754048351, 11633.767324653545, 11701.18091733579, 11737.53049878004, 11737.94131029348, 11740.236884602558, 4332.740035597812, 4434.246178149658, 4546.113362954403, 4561.178987782231, 4566.840160594364, 9893.554790034466, 9955.104040636234, 9960.601575937026, 9963.0, 9970.700878973643, 5126.948730512647, 5150.48495150482, 5150.562202212854, 5170.095233334151, 5175.120229560908, 4273.380359847804, 4560.5895469407915, 4678.274867395491, 4751.952480475151, 4761.40482439695, 3714.647108524686, 3768.2840260350995, 3823.6941044716355, 3857.479240302523, 3887.71705337238, 1202.5189924030458, 1222.175031853513, 1225.5526732742485, 1236.4188831249298, 1246.2896149194871, 884.3500027479797, 991.8264303746815, 1022.5398570643091, 1040.8282633365561, 1041.7864337810731, 5736.598438109376, 5804.535637149018, 5896.658659968367, 5994.460498555064, 6037.335893051761, 441.7813182474696, 444.11620874459595, 459.37983721975064, 463.15243105117554, 465.4693649239869, 4208.915821683549, 4247.787610619456, 4258.446401967724, 4263.126059157865, 4272.7222730523035, 10063.446510441849, 10251.538680759384, 10326.122279606167, 10330.0, 10345.431001295, 10167.491625418847, 10234.385936843813, 10237.692869213935, 10260.743481413025, 10272.640457584099, 7907.169747176464, 7937.523748575104, 7939.801504962306, 7988.640511177011, 7989.0413150421155, 5911.556633252438, 5924.407559244213, 6010.188624535632, 6036.728347224298, 6096.02463605821, 9054.139856713602, 9515.762105947406, 9581.377210481296, 9618.374805637612, 9694.75763105928, 9477.336586438883, 9650.08324209199, 9651.951740241253, 9700.078492543196, 9731.075547822946, 6676.799696009128, 7177.677004534913, 7339.192688804121, 7427.22014188524, 7577.393808495417, 8271.090180080066, 8283.420160588701, 8312.08567057611, 8352.0, 8376.958115209385, 7478.439117066133, 7716.498427602188, 7732.149463559116, 7743.186965368781, 7785.260400261966, 7516.5114267572435, 7520.420711650396, 7555.168931417649, 7600.391968642606, 7654.196309387434, 6988.510804243654, 6992.1259842519685, 7009.0, 7055.717771289091, 7094.64526773649, 5168.8449346519665, 5279.366476022902, 5331.973340133274, 5373.301470808769, 5528.115501519737, 5213.728162557304, 5266.104762190514, 5269.683818970874, 5336.039512887717, 5358.035553600389], "value": 4459.763865990988, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.13934655124257, 326.42060342891364, 332.60420100080904, 335.3460751534505, 338.20015662957104, 5405.648632838873, 5527.834164975057, 5588.329400471944, 5660.877171060853, 5687.63030403024, 5568.86077848054, 5576.637518561301, 5588.524975377088, 5613.07384281593, 5637.830865074044, 4404.735715857043, 4415.889602759925, 4421.960839202777, 4427.645788336941, 4431.293951828537, 4176.45706058212, 4398.582134697199, 4403.185410699015, 4420.458236514018, 4451.5103338632725, 4316.460442444694, 4319.589638984291, 4326.415933848922, 4358.0, 4409.162259170747, 4322.351647252905, 4379.912401751979, 4381.78091095444, 4388.341748737683, 4407.427034485529, 3768.1898391845884, 3833.5208098987628, 3837.3860182370686, 3843.961560384388, 3847.2882516734494, 5475.6440831346235, 5496.258005169331, 5510.338759348863, 5518.33779946409, 5519.0, 4963.7766300516205, 4973.726445045521, 4985.501449855022, 5005.149124648782, 5008.649394542384, 5657.8585535361535, 5885.676287804169, 5886.793962211302, 5887.0, 5894.701066584035, 4169.541350451448, 4170.9582904170875, 4178.791060446996, 4195.139996300751, 4223.97888010558, 6211.34780848009, 6212.223472065992, 6212.502999760009, 6220.377962203744, 6256.655883926383, 4218.177455396169, 4264.275073237557, 4286.571342865688, 4294.248506511411, 4314.69797114199, 4301.978490107529, 4307.22469955411, 4312.54718254585, 4355.346697995326, 4361.672874534423, 5017.347744793154, 5026.472220416804, 5038.193888977746, 5040.57155141817, 5046.318746969112, 5639.689817060019, 5949.881002379928, 5952.3154837193415, 6011.609245399035, 6030.276366836008, 1477.0842077911548, 1492.2389581313607, 1495.110409306461, 1502.369005017895, 1505.646173149309, 934.2228830802894, 943.1511639524346, 950.4867371619248, 959.2565761534867, 960.4525420510242, 5040.672356296829, 5109.2117758784125, 5113.258577506241, 5145.845624631267, 5161.425765141593, 5382.838514844561, 5386.326709161355, 5390.97304513475, 5396.487333703292, 5444.121777986588, 5030.542935986165, 5050.6464547482055, 5069.949300507022, 5111.156659151198, 5131.512506311894, 2890.9763165784034, 2911.7525010374356, 2919.71532525688, 2920.5765164051095, 2954.985225073861, 5548.447714051582, 5580.832575022756, 5604.915926261109, 5673.517750991129, 5688.573356998241, 5346.742215234288, 5371.409144994048, 5467.4532546745, 5476.780928762805, 5498.762778374834, 5532.474414930575, 5578.7447824240035, 5595.272614560163, 5602.887942241132, 5642.830715078554, 5359.008583412081, 5365.946340536545, 5408.702521361363, 5506.238003838733, 5666.7533142708535, 6067.998780201217, 6070.876887775776, 6075.635461872125, 6089.756409743561, 6110.297315808739, 6042.305134909366, 6185.505159587312, 6367.707625254812, 6376.298607153117, 6383.116980490673, 4218.460262004408, 4230.111676547959, 4240.724352917051, 4251.171021650748, 4257.233697934402, 6140.600860944024, 6201.720922558586, 6219.751209951733, 6227.878981783322, 6230.844228894314, 5818.2436283282905, 5916.822495325147, 5952.732127054206, 5956.0, 5991.580589358703, 5966.940330596639, 5980.949715645139, 6019.729112189874, 6026.457618814327, 6035.36628653985, 5797.710114494343, 5953.0, 5997.640141591519, 6013.248343957005, 6047.460066771879, 5994.700264986646, 6001.2498437695285, 6028.547858910687, 6037.607555508878, 6051.243594550681, 5750.223719797774, 5775.0, 5841.0, 5850.151727999331, 5851.683371241436], "value": 4390.7076678527255, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.3697231298023, 812.9756107316779, 839.5256679975813, 849.2356878809068, 865.1737590600977, 14822.407103715852, 16479.67040659186, 16512.0, 16525.917370413146, 16548.0, 15373.461928832494, 15652.84347156528, 15876.761848572281, 16043.438479653214, 16057.277422515986, 826.0500424511799, 836.4103307168432, 837.4849467577426, 840.3445312656107, 840.3781201910573, 809.6478032056045, 831.7005877883956, 835.1898658301438, 836.0469065265602, 836.2849763452252, 861.8147098373848, 867.4925168776274, 867.8611422172454, 869.4696235296495, 874.3442418186361, 862.5730263519566, 868.6178081644057, 869.2481003931586, 870.8127752533204, 874.6763697431927, 629.2291942370605, 630.4137152448221, 633.0283015571122, 633.1326083265955, 635.139386131794, 14522.419103235858, 14549.12705237689, 14586.416543338253, 14623.561293161221, 14669.633259168499, 15227.619309517351, 15450.690986180325, 15452.0, 15474.45839395627, 15565.455209067628, 12549.937250313782, 12623.747525049448, 12678.239305641599, 12740.0, 12821.102522823407, 3964.444977703124, 3965.345717956534, 3973.304671682473, 3975.9602403975878, 4076.8165432555606, 82360.58819705863, 83150.0, 83710.0, 84644.0, 85253.99999999937, 10724.302920310154, 10796.298240614335, 10870.456477176267, 11510.366929818772, 11638.883611163948, 21523.461913452287, 21539.78460215409, 21596.676049859263, 21651.56696866054, 21661.783382166293, 8592.269657079094, 8731.214190722738, 8768.824623507495, 8799.692010779592, 9001.954990225007, 4838.491958344393, 4892.657513974059, 4930.112579735682, 4945.060438516671, 5046.091703493406, 3557.626449222846, 3634.509341238952, 3672.394054980898, 3680.8895733128047, 3878.204967981557, 708.9432845372357, 710.9573425594377, 716.4698123388655, 718.0521711341084, 725.0755287009106, 649.8635286589774, 767.7543186180369, 799.5562462833067, 800.3237264511479, 810.4124509730399, 10436.37381757097, 10634.415107169025, 10855.131589472823, 10860.294080884718, 11114.110871130451, 666.6433458099893, 762.5043721580906, 767.6699019421591, 768.1588660416835, 795.9562224077731, 4883.169861123618, 5043.394792624908, 5053.36832895888, 5110.2590124431745, 5186.559142472856, 121120.78879211271, 129868.35065824799, 131056.3447182777, 133020.679172832, 133328.0, 112189.0, 116772.0, 117547.41226293985, 119814.0, 120421.39789300997, 63993.36006639967, 64301.0, 101624.0, 102239.0, 103350.0, 9715.854262186214, 9725.562349694139, 9748.610055597697, 9869.703908882744, 9964.55159517838, 56973.7328893813, 57057.71471142784, 58269.70865145647, 58332.0, 58462.4153758474, 81498.59250703946, 83552.58223708843, 85020.14979850122, 85931.0, 86024.0, 13652.180869147884, 13722.245276509888, 13816.378262977989, 14037.929810350883, 14115.294235287989, 16035.278412471234, 16253.67492650164, 16283.430079947146, 16337.0, 16661.416850410176, 13852.16886986784, 14151.150930944177, 14294.285285735463, 14706.852931470548, 14737.778933316224, 14685.779713304522, 15056.548303550911, 15102.0, 15355.0, 15904.681906361577, 14157.221352825281, 14707.92646036763, 14763.92618036903, 14892.553223403314, 15710.450134245246, 12248.938755306166, 12467.376631168587, 12695.619131426072, 12766.382891507405, 12809.0, 11224.0, 11688.770207708696, 12040.698982525508, 12646.810297845355, 12744.872551274368], "value": 8051.824564626583, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1521.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 106.0, 112.0, 111.0, 114.0, 114.0, 112.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 145.0, 178.0, 120.0, 144.0, 144.0, 123.0, 193.0, 118.0], "value": 142.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 161.0, 161.0, 164.0, 175.0, 164.0, 161.0, 160.0], "value": 163.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 163.0, 76.0, 76.0, 76.0, 75.0, 180.0, 75.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 151.0, 85.0, 84.0, 84.0, 150.0, 85.0, 85.0, 143.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 121.0, 118.0, 119.0, 117.0, 117.0, 117.0, 118.0, 117.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 147.0, 147.0, 147.0, 146.0, 147.0, 147.0, 147.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 95.0, 97.0, 96.0, 95.0, 96.0, 95.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 55.0, 60.0, 53.0, 60.0, 53.0, 60.0, 53.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 62.0, 61.0, 62.0, 61.0, 63.0, 61.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 75.0, 78.0, 75.0, 75.0, 76.0, 84.0, 77.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 124.0, 119.0, 121.0, 133.0, 123.0, 123.0, 129.0, 178.0, 122.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 156.0, 158.0, 162.0, 157.0, 154.0, 156.0, 168.0, 157.0, 155.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 70.0, 64.0, 62.0, 65.0, 62.0, 63.0, 63.0, 63.0], "value": 64.3, "unit": "score"}]}]} 22:26:38 INFO - Return code: 0 22:26:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:26:38 ERROR - # TBPL SUCCESS # 22:26:38 INFO - Running post-action listener: _resource_record_post_action 22:26:38 INFO - [mozharness: 2016-07-20 05:26:38.827808Z] Finished run-tests step (success) 22:26:38 INFO - Running post-run listener: _resource_record_post_run 22:26:38 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 560443392; Read time: 0; Write time: 417776 22:26:38 INFO - TinderboxPrint: CPU usage
13.4% 22:26:38 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 22:26:38 INFO - TinderboxPrint: I/O write bytes / time
560,443,392 / 417,776 22:26:38 INFO - TinderboxPrint: CPU idle
8,225.7 (86.6%) 22:26:38 INFO - TinderboxPrint: CPU user
1,218.4 (12.8%) 22:26:38 INFO - TinderboxPrint: Swap in / out
0 / 0 22:26:38 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 262799360; Read time: 0; Write time: 244888 22:26:38 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 292618240; Read time: 0; Write time: 171212 22:26:39 INFO - Running post-run listener: _upload_blobber_files 22:26:39 INFO - Blob upload gear active. 22:26:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:26:39 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 22:26:39 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:26:39 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:26:39 INFO - (blobuploader) - INFO - Open directory for files ... 22:26:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:26:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:26:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:40 INFO - (blobuploader) - INFO - Done attempting. 22:26:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:26:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:26:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:51 INFO - (blobuploader) - INFO - Done attempting. 22:26:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:26:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:26:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:26:52 INFO - (blobuploader) - INFO - Done attempting. 22:26:52 INFO - (blobuploader) - INFO - Iteration through files over. 22:26:52 INFO - Return code: 0 22:26:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:26:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:26:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494d3be55841f2bd814c0bf06b3b0b7de18110223bf21a81052f3c0a15829ef015302922351b32b269438ad640b339fce8048afe058f22c007ad9ad979f8fb01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b7a2577f24bf973263ac3cee23a5f12863cf7e6eed34b3078b396e4f705f208ca255dc65bbe6f2ee47cce765600a8a59af3c9948e1737c5c657407e617deff5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca3e4a035a5284b45b15a6bd3b778590d61c6249fac736584de9045d6694b4576d0f4e478042d2410490acc1c325b264c832ad96b44a49553cdc649226c17b93"} 22:26:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:26:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:26:52 INFO - Contents: 22:26:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494d3be55841f2bd814c0bf06b3b0b7de18110223bf21a81052f3c0a15829ef015302922351b32b269438ad640b339fce8048afe058f22c007ad9ad979f8fb01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b7a2577f24bf973263ac3cee23a5f12863cf7e6eed34b3078b396e4f705f208ca255dc65bbe6f2ee47cce765600a8a59af3c9948e1737c5c657407e617deff5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca3e4a035a5284b45b15a6bd3b778590d61c6249fac736584de9045d6694b4576d0f4e478042d2410490acc1c325b264c832ad96b44a49553cdc649226c17b93"} 22:26:52 INFO - Running post-run listener: copy_logs_to_upload_dir 22:26:52 INFO - Copying logs to upload dir... 22:26:52 INFO - mkdir: /builds/slave/test/build/upload/logs 22:26:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.880642 ========= master_lag: 0.90 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-07-19 22:26:53.298806) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 22:26:53.303802) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157 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/sha512/494d3be55841f2bd814c0bf06b3b0b7de18110223bf21a81052f3c0a15829ef015302922351b32b269438ad640b339fce8048afe058f22c007ad9ad979f8fb01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b7a2577f24bf973263ac3cee23a5f12863cf7e6eed34b3078b396e4f705f208ca255dc65bbe6f2ee47cce765600a8a59af3c9948e1737c5c657407e617deff5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca3e4a035a5284b45b15a6bd3b778590d61c6249fac736584de9045d6694b4576d0f4e478042d2410490acc1c325b264c832ad96b44a49553cdc649226c17b93"} build_url:https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011119 build_url: 'https://queue.taskcluster.net/v1/task/VjDhAi8URMSmJJ_-x_4rZw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/494d3be55841f2bd814c0bf06b3b0b7de18110223bf21a81052f3c0a15829ef015302922351b32b269438ad640b339fce8048afe058f22c007ad9ad979f8fb01", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6b7a2577f24bf973263ac3cee23a5f12863cf7e6eed34b3078b396e4f705f208ca255dc65bbe6f2ee47cce765600a8a59af3c9948e1737c5c657407e617deff5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ca3e4a035a5284b45b15a6bd3b778590d61c6249fac736584de9045d6694b4576d0f4e478042d2410490acc1c325b264c832ad96b44a49553cdc649226c17b93"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 22:26:53.332219) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:26:53.332634) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=20e666a3d4cfbb71505a330e000001b4-1468991175.76403-118398157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004444 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 22:26:53.382100) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 22:26:53.385478) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 22:26:53.385766) ========= ========= Total master_lag: 1.02 =========